"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Starting (try 1/5) 2026/02/03 01:51:55 DEBUG : Creating backend with remote "TestDrime:rclone-test-pepecuh4kece" 2026/02/03 01:51:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 01:51:56 DEBUG : Creating backend with remote "/tmp/rclone660238072" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.03s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:51:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:51:57 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2026/02/03 01:51:57 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:51:57 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.70s) === RUN TestCopy run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:51:58 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/03 01:51:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:51:58 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:51:58 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:52:01 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:52: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 2026/02/03 01:52:03 ERROR : error listing: directory not found fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 01:52:05 ERROR : error listing: directory not found fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go: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 01:52:09 DEBUG : drime root 'rclone-test-pepecuh4kece': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestCopy Messages: directories --- FAIL: TestCopy (26.06s) === RUN TestCopyMetadata run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:98: Skipping as metadata not supported 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 --- SKIP: TestCopyMetadata (14.24s) === RUN TestCopyMetadataNoEmptyDirs run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.41s) === RUN TestCopyMissingDirectory run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:52:39 DEBUG : Creating backend with remote "/non-existing" 2026/02/03 01:52:39 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2026/02/03 01:52:39 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:52:39 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.76s) === RUN TestCopyNoTraverse run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:52:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:52:40 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:52:40 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:52:43 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:52:43 INFO : sub dir/hello world: Copied (new) run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestCopyNoTraverse Messages: directories --- FAIL: TestCopyNoTraverse (18.49s) === RUN TestCopyNoTraverseDeadlock run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:222: Only runs on local 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 --- SKIP: TestCopyNoTraverseDeadlock (14.29s) === RUN TestCopyCheckFirst run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:53:12 INFO : drime root 'rclone-test-pepecuh4kece': Running all checks before starting transfers 2026/02/03 01:53:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:53:13 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:53:13 INFO : drime root 'rclone-test-pepecuh4kece': Checks finished, now starting transfers 2026/02/03 01:53:13 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:53:16 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:53:16 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 (19.38s) === RUN TestSyncNoTraverse run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:53:32 ERROR : Ignoring --no-traverse with sync 2026/02/03 01:53:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:53:32 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:53:32 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:53:34 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:53:34 INFO : sub dir/hello world: Copied (new) 2026/02/03 01:53:34 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 run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty --- PASS: TestSyncNoTraverse (18.82s) === RUN TestCopyWithDepth run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:53:51 DEBUG : hello world2: Need to transfer - File not found at Destination 2026/02/03 01:53:51 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:53:51 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:53:54 DEBUG : hello world2: size = 12 OK 2026/02/03 01:53:54 INFO : hello world2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 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: TestCopyWithDepth (29.11s) === RUN TestCopyWithFilesFrom run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:54:20 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 01:54:20 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:54:20 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:54:22 DEBUG : potato2: size = 11 OK 2026/02/03 01:54:22 INFO : potato2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go: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: TestCopyWithFilesFrom (22.34s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:54:42 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 01:54:42 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:54:42 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:54:45 DEBUG : potato2: size = 11 OK 2026/02/03 01:54:45 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/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" --- FAIL: TestCopyWithFilesFromAndNoTraverse (12.76s) === RUN TestCopyEmptyDirectories run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:54:55 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/03 01:54:55 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/03 01:54:55 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/02/03 01:54:55 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/03 01:54:56 INFO : sub dir2: Making directory 2026/02/03 01:54:57 INFO : sub dir: Making directory 2026/02/03 01:54:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:54:58 INFO : sub dir2/sub sub dir2: Making directory 2026/02/03 01:54:59 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:54:59 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:55:01 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01: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 2026/02/03 01:55:11 DEBUG : drime root 'rclone-test-pepecuh4kece': 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 (36.21s) === RUN TestCopyNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:55:31 INFO : sub dir2: Making directory 2026/02/03 01:55:31 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/03 01:55:31 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/03 01:55:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:55:33 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:55:33 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:55:36 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:55:36 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 (30.58s) === RUN TestMoveEmptyDirectories run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:56:02 DEBUG : sub dir2: Making directory with metadata 2026/02/03 01:56:02 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/03 01:56:02 INFO : sub dir2: Making directory 2026/02/03 01:56:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:56:03 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:56:03 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:56:06 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:56:06 INFO : sub dir/hello world: Copied (new) 2026/02/03 01:56:06 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:292: Sleeping for 6s just to make sure 2026/02/03 01:56:15 DEBUG : drime root 'rclone-test-pepecuh4kece': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2026/02/03 01:56:15 DEBUG : drime root 'rclone-test-pepecuh4kece': 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 --- PASS: TestMoveEmptyDirectories (18.48s) === RUN TestSyncNoUpdateDirModtime run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:438: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.75s) === RUN TestMoveNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:56:21 INFO : sub dir2: Making directory 2026/02/03 01:56:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:56:21 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:56:21 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:56:26 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:56:26 INFO : sub dir/hello world: Copied (new) 2026/02/03 01:56:26 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:292: Sleeping for 7s just to make sure run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty --- PASS: TestMoveNoEmptyDirectories (22.83s) === RUN TestSyncEmptyDirectories run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:56:44 DEBUG : sub dir2: Making directory with metadata 2026/02/03 01:56:44 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/03 01:56:44 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/03 01:56:44 INFO : sub dir2: Making directory 2026/02/03 01:56:44 INFO : sub dir: Making directory 2026/02/03 01:56:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:56:45 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:56:45 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:56:48 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:56:48 INFO : sub dir/hello world: Copied (new) 2026/02/03 01:56:48 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516 Error: Should be true Test: TestSyncEmptyDirectories Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516 Error: Not equal: expected: 0 actual : 1 Test: TestSyncEmptyDirectories Messages: 1 objects not found fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516 Error: Not equal: expected: []string{"sub dir", "sub dir2"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) (len=7) "sub dir", - (string) (len=8) "sub dir2" +([]string) { } Test: TestSyncEmptyDirectories Messages: directories 2026/02/03 01:56:56 DEBUG : drime root 'rclone-test-pepecuh4kece': 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: TestSyncEmptyDirectories 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: TestSyncEmptyDirectories 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", "sub dir2"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=8) "sub dir2" } Test: TestSyncEmptyDirectories Messages: directories --- FAIL: TestSyncEmptyDirectories (27.05s) === RUN TestSyncSetDelayedModTimes run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set 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:1156 /usr/local/go/src/testing/testing.go:1132 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:537 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: TestSyncSetDelayedModTimes Messages: directories --- FAIL: TestSyncSetDelayedModTimes (10.03s) === RUN TestSyncNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 01:57:21 INFO : sub dir2: Making directory 2026/02/03 01:57:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:57:21 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:57:21 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:57:24 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:57:24 INFO : sub dir/hello world: Copied (new) 2026/02/03 01:57:24 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:599 Error: Should be true Test: TestSyncNoEmptyDirectories 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:599 Error: Not equal: expected: 0 actual : 1 Test: TestSyncNoEmptyDirectories 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:599 Error: Not equal: expected: []string{"sub dir"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,3 +1,2 @@ -([]string) (len=1) { - (string) (len=7) "sub dir" +([]string) { } Test: TestSyncNoEmptyDirectories 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: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: TestSyncNoEmptyDirectories 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: TestSyncNoEmptyDirectories 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: TestSyncNoEmptyDirectories Messages: directories --- FAIL: TestSyncNoEmptyDirectories (25.55s) === RUN TestServerSideCopy run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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:615 Error: Should be true Test: TestServerSideCopy 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:615 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideCopy Messages: 1 objects not found 2026/02/03 01:57:59 DEBUG : Creating backend with remote "TestDrime:rclone-test-qogewok9xemi" sync_test.go:620: Server side copy (if possible) drime root 'rclone-test-pepecuh4kece' -> drime root 'rclone-test-qogewok9xemi' 2026/02/03 01:58:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:58:01 DEBUG : drime root 'rclone-test-qogewok9xemi': Waiting for checks to finish 2026/02/03 01:58:01 DEBUG : drime root 'rclone-test-qogewok9xemi': Waiting for transfers to finish 2026/02/03 01:58:03 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:58:03 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/03 01:58:04 DEBUG : drime root 'rclone-test-qogewok9xemi': 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 (30.43s) === RUN TestCopyOverSelf run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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 01:58:32 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 01:58:32 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone660238072) 2026/02/03 01:58:32 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 01:58:32 DEBUG : sub dir/hello world: Sizes differ 2026/02/03 01:58:32 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 01:58:33 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/03 01:58:33 DEBUG : sub dir/hello world: size = 17 OK 2026/02/03 01:58:33 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:292: Sleeping for 6s just to make sure 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: TestCopyOverSelf Messages: directories --- FAIL: TestCopyOverSelf (42.34s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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 01:59:19 DEBUG : Creating backend with remote "TestDrime:rclone-test-mipufix1soha" sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-pepecuh4kece' -> drime root 'rclone-test-mipufix1soha' 2026/02/03 01:59:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 01:59:20 DEBUG : drime root 'rclone-test-mipufix1soha': Waiting for checks to finish 2026/02/03 01:59:20 DEBUG : drime root 'rclone-test-mipufix1soha': Waiting for transfers to finish 2026/02/03 01:59:22 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 01:59:22 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/03 01:59:26 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: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:665 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: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:665 Error: Not equal: expected: 17 actual : 11 Test: TestServerSideCopyOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 2026/02/03 01:59:36 DEBUG : drime root 'rclone-test-mipufix1soha': Waiting for checks to finish 2026/02/03 01:59:36 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 01:59:36 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-mipufix1soha') 2026/02/03 01:59:36 DEBUG : sub dir/hello world: Sizes differ 2026/02/03 01:59:36 DEBUG : drime root 'rclone-test-mipufix1soha': Waiting for transfers to finish 2026/02/03 01:59:36 DEBUG : sub dir/hello world: Server side copy: removing existing object after successful copy 2026/02/03 01:59:37 DEBUG : sub dir/hello world: size = 17 OK 2026/02/03 01:59:37 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 01:59:46 DEBUG : drime root 'rclone-test-mipufix1soha': Purge remote run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestServerSideCopyOverSelf Messages: directories --- FAIL: TestServerSideCopyOverSelf (61.91s) === RUN TestMoveOverSelf run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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 02:00:17 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:00:17 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone660238072) 2026/02/03 02:00:17 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 02:00:17 DEBUG : sub dir/hello world: Sizes differ 2026/02/03 02:00:17 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:00:20 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/03 02:00:20 DEBUG : sub dir/hello world: size = 17 OK 2026/02/03 02:00:20 INFO : sub dir/hello world: Copied (replaced existing) 2026/02/03 02:00:20 INFO : sub dir/hello world: Deleted run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty 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: TestMoveOverSelf Messages: directories --- FAIL: TestMoveOverSelf (33.93s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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 02:00:51 DEBUG : Creating backend with remote "TestDrime:rclone-test-puxutob6vizo" sync_test.go:701: Server side copy (if possible) drime root 'rclone-test-pepecuh4kece' -> drime root 'rclone-test-puxutob6vizo' 2026/02/03 02:00:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 02:00:52 DEBUG : drime root 'rclone-test-puxutob6vizo': Waiting for checks to finish 2026/02/03 02:00:52 DEBUG : drime root 'rclone-test-puxutob6vizo': Waiting for transfers to finish 2026/02/03 02:00:54 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 02:00:54 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/03 02:00:57 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: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:710 Error: Should be true Test: TestServerSideMoveOverSelf 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:710 Error: Not equal: expected: 17 actual : 11 Test: TestServerSideMoveOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 2026/02/03 02:01:07 DEBUG : drime root 'rclone-test-puxutob6vizo': Using server-side directory move 2026/02/03 02:01:07 INFO : drime root 'rclone-test-puxutob6vizo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/03 02:01:08 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 02:01:08 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-puxutob6vizo') 2026/02/03 02:01:08 DEBUG : sub dir/hello world: Sizes differ 2026/02/03 02:01:08 DEBUG : drime root 'rclone-test-puxutob6vizo': Waiting for checks to finish 2026/02/03 02:01:08 DEBUG : drime root 'rclone-test-puxutob6vizo': Waiting for transfers to finish 2026/02/03 02:01:09 INFO : sub dir/hello world: Deleted 2026/02/03 02:01:10 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 02:01:10 DEBUG : drime root 'rclone-test-puxutob6vizo': Purge remote 2026/02/03 02:01:11 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:01:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:01:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:01:11 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:01:11 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:01:11 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:01:12 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:01:12 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:01:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:01:12 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:01:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:01:12 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:01:12 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:01:12 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:01:13 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:01:13 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:01:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:01:14 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:01:17 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:01:17 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 02:01:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:01:22 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:01:32 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:01:53 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:02:12 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:02:32 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:02:52 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:03:12 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:03:32 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:03:52 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:04:12 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 02:04:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:04:52 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:05:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:05:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:05:52 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:06:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:06:32 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:06:53 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:07:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:07:32 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 02:07:53 DEBUG : pacer: Reducing sleep to 10s 2026/02/03 02:08:12 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 02:08:22 DEBUG : pacer: Reducing sleep to 2.5s --- FAIL: TestServerSideMoveOverSelf (467.41s) === RUN TestCopyAfterDelete run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:08:28 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 02:08:30 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 02:08:32 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 02:08:33 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 02:08:33 ERROR : error listing: directory not found 2026/02/03 02:08:33 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 02:08:34 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 02:08:34 INFO : Local file system at /tmp/rclone660238072: Making directory 2026/02/03 02:08:34 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 02:08:34 DEBUG : pacer: Reducing sleep to 10ms 2026/02/03 02:08:34 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:08:34 DEBUG : drime root 'rclone-test-pepecuh4kece': 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 (26.13s) === RUN TestCopyRedownload run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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 02:09:03 DEBUG : Added delayed dir = "sub dir", newDst= 2026/02/03 02:09:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 02:09:03 DEBUG : Local file system at /tmp/rclone660238072: Waiting for checks to finish 2026/02/03 02:09:03 DEBUG : Local file system at /tmp/rclone660238072: Waiting for transfers to finish 2026/02/03 02:09:04 DEBUG : sub dir/hello world.9076d4bf.partial: size = 11 OK 2026/02/03 02:09:04 DEBUG : sub dir/hello world.9076d4bf.partial: renamed to: sub dir/hello world 2026/02/03 02:09:04 INFO : sub dir/hello world: Copied (new) 2026/02/03 02:09:04 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty --- PASS: TestCopyRedownload (19.29s) === RUN TestSyncBasedOnCheckSum run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:09:08 DEBUG : check sum: Need to transfer - File not found at Destination 2026/02/03 02:09:08 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:09:08 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:09:10 DEBUG : check sum: size = 1 OK 2026/02/03 02:09:10 INFO : check sum: Copied (new) 2026/02/03 02:09:10 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/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:789 Error: Should be true Test: TestSyncBasedOnCheckSum Messages: listing wrong, want check sum (1) got fstest.go:203: Not found "check sum" 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:789 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBasedOnCheckSum Messages: 1 objects not found 2026/02/03 02:09:19 DEBUG : check sum: size = 1 OK 2026/02/03 02:09:19 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:09:19 NOTICE: drime root 'rclone-test-pepecuh4kece': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/02/03 02:09:19 DEBUG : check sum: Size of src and dst objects identical 2026/02/03 02:09:19 DEBUG : check sum: Unchanged skipping 2026/02/03 02:09:19 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:09:19 DEBUG : Waiting for deletions to finish 2026/02/03 02:09:19 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: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: TestSyncBasedOnCheckSum Messages: listing wrong, want got check sum (1) 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: TestSyncBasedOnCheckSum Messages: Unexpected file "check sum" --- FAIL: TestSyncBasedOnCheckSum (20.45s) === RUN TestSyncSizeOnly run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:09:29 DEBUG : sizeonly: Need to transfer - File not found at Destination 2026/02/03 02:09:29 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:09:29 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:09:31 DEBUG : sizeonly: size = 6 OK 2026/02/03 02:09:31 INFO : sizeonly: Copied (new) 2026/02/03 02:09:31 DEBUG : Waiting for deletions to finish 2026/02/03 02:09:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:09:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:09:32 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:09:32 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:09:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:09:32 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:09:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:09:32 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:09:32 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:09:32 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:09:33 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:09:33 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:09:33 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:09:33 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:09:34 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:09:34 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:09:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:09:35 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:09:37 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:09:37 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/03 02:09:37 ERROR : check sum: Couldn't delete: failed to delete item: Error "Server Error" 2026/02/03 02:09:37 ERROR : drime root 'rclone-test-pepecuh4kece': not deleting directories as there were IO errors sync_test.go:822: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:822 Error: Received unexpected error: failed to delete 1 files Test: TestSyncSizeOnly 2026/02/03 02:09:43 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/03 02:09:53 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/03 02:09:58 DEBUG : pacer: Reducing sleep to 1.28s --- FAIL: TestSyncSizeOnly (29.64s) === RUN TestSyncIgnoreSize run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:10:00 DEBUG : pacer: Reducing sleep to 640ms 2026/02/03 02:10:00 DEBUG : ignore-size: Need to transfer - File not found at Destination 2026/02/03 02:10:00 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:10:00 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:10:02 DEBUG : pacer: Reducing sleep to 320ms 2026/02/03 02:10:04 DEBUG : pacer: Reducing sleep to 160ms 2026/02/03 02:10:04 INFO : ignore-size: Copied (new) 2026/02/03 02:10:04 DEBUG : Waiting for deletions to finish 2026/02/03 02:10:04 DEBUG : pacer: Reducing sleep to 80ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:10:06 DEBUG : pacer: Reducing sleep to 40ms 2026/02/03 02:10:06 DEBUG : pacer: Reducing sleep to 20ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:10:08 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 7s just to make sure 2026/02/03 02:10:16 DEBUG : ignore-size: Sizes identical 2026/02/03 02:10:16 DEBUG : ignore-size: Unchanged skipping 2026/02/03 02:10:16 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:10:16 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:10:16 DEBUG : Waiting for deletions to finish 2026/02/03 02:10:16 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: TestSyncIgnoreSize (30.06s) === RUN TestSyncIgnoreTimes run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:10:34 DEBUG : existing: size = 6 OK 2026/02/03 02:10:34 DEBUG : existing: Sizes identical 2026/02/03 02:10:34 DEBUG : existing: Unchanged skipping 2026/02/03 02:10:34 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:10:34 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:10:34 DEBUG : Waiting for deletions to finish 2026/02/03 02:10:34 INFO : There was nothing to transfer 2026/02/03 02:10:34 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2026/02/03 02:10:34 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:10:34 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:10:36 DEBUG : existing: Removing old object on successful upload 2026/02/03 02:10:36 DEBUG : existing: size = 6 OK 2026/02/03 02:10:36 INFO : existing: Copied (replaced existing) 2026/02/03 02:10:36 DEBUG : Waiting for deletions to finish 2026/02/03 02:10:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:10:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:10:37 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:10:37 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:10:37 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:10:37 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:10:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:10:38 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:10:38 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:10:38 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:10:38 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:10:38 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:10:39 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:10:39 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:10:39 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:10:39 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:10:40 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:10:40 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:10:43 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:10:43 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 02:10:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:10:48 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:10:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:11:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:11:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:11:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:12:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:12:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:12:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:13:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:13:38 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 02:13:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:14:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:14:39 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:14:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:15:18 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:15:39 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:15:59 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:16:18 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:16:38 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:16:58 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 02:17:19 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 02:17:39 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 02:17:49 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 02:17:54 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 02:17:56 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 (452.30s) === RUN TestSyncIgnoreExisting run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:18:00 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 02:18:01 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 02:18:01 DEBUG : existing: Destination exists, skipping 2026/02/03 02:18:01 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:18:01 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:18:01 DEBUG : Waiting for deletions to finish 2026/02/03 02:18:01 INFO : There was nothing to transfer 2026/02/03 02:18:02 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 02:18:02 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 02:18:02 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:18:02 DEBUG : existing: Destination exists, skipping 2026/02/03 02:18:02 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:18:02 DEBUG : Waiting for deletions to finish 2026/02/03 02:18:02 INFO : There was nothing to transfer 2026/02/03 02:18:02 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 02:18:02 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.33s) === RUN TestSyncIgnoreErrors run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:18:20 INFO : d: Making directory 2026/02/03 02:18:22 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/03 02:18:22 DEBUG : c/non empty space: size = 5 OK 2026/02/03 02:18:22 DEBUG : c/non empty space: Sizes identical 2026/02/03 02:18:22 DEBUG : c/non empty space: Unchanged skipping 2026/02/03 02:18:22 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:18:22 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:18:26 DEBUG : a/potato2: size = 60 OK 2026/02/03 02:18:26 INFO : a/potato2: Copied (new) 2026/02/03 02:18:26 DEBUG : Waiting for deletions to finish 2026/02/03 02:18:26 INFO : b/potato: Deleted 2026/02/03 02:18:26 INFO : d: Removing directory 2026/02/03 02:18:26 INFO : b: Removing directory 2026/02/03 02:18:27 DEBUG : b: Failed to Rmdir: directory not empty 2026/02/03 02:18:27 DEBUG : drime root 'rclone-test-pepecuh4kece': failed to delete 1 directories 2026/02/03 02:18:27 DEBUG : drime root 'rclone-test-pepecuh4kece': 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:994 Error: Not equal: expected: []string{"a", "c"} actual : []string{"a", "b", "c"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=2) { +([]string) (len=3) { (string) (len=1) "a", + (string) (len=1) "b", (string) (len=1) "c" Test: TestSyncIgnoreErrors Messages: directories run.go:130: removing dir "c" failed - try 1/3: directory not empty run.go:130: removing dir "c" failed - try 2/3: directory not empty run.go:130: removing dir "c" failed - try 3/3: directory not empty run.go:133: removing dir "c" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"c"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=1) "c" } Test: TestSyncIgnoreErrors Messages: directories --- FAIL: TestSyncIgnoreErrors (41.86s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:18:56 DEBUG : empty space: size = 1 OK 2026/02/03 02:18:56 DEBUG : empty space: Sizes identical 2026/02/03 02:18:56 DEBUG : empty space: Unchanged skipping 2026/02/03 02:18:56 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:18:56 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:18:56 DEBUG : Waiting for deletions to finish 2026/02/03 02:18:56 NOTICE: c: Skipped remove directory as --dry-run is set 2026/02/03 02:18:56 DEBUG : drime root 'rclone-test-pepecuh4kece': deleted 1 directories 2026/02/03 02:18:56 INFO : There was nothing to transfer 2026/02/03 02:18:57 DEBUG : empty space: size = 1 OK 2026/02/03 02:18:57 DEBUG : empty space: Sizes identical 2026/02/03 02:18:57 DEBUG : empty space: Unchanged skipping 2026/02/03 02:18:57 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:18:57 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:18:57 DEBUG : Waiting for deletions to finish 2026/02/03 02:18:57 INFO : c: Removing directory 2026/02/03 02:18:58 DEBUG : drime root 'rclone-test-pepecuh4kece': deleted 1 directories 2026/02/03 02:18:58 INFO : There was nothing to transfer 2026/02/03 02:19:00 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:19:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:19:00 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:19:00 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:19:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:19:01 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:19:01 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:19:01 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:19:01 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:19:01 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:19:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:19:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:19:02 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:19:02 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:19:02 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:19:02 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:19:04 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:19:04 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:19:06 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:19:06 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing dir "c" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 02:19:12 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/03 02:19:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:19:22 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/03 02:19:27 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:19:27 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:19:37 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:19:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:20:17 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:20:37 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:20:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:21:17 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:21:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:21:57 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "c" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 02:22:17 DEBUG : pacer: Reducing sleep to 10s 2026/02/03 02:22:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:22:37 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:22:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:23:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:23:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:23:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:24:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:24:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:24:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:25:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:25:28 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "c" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "c" failed: failed to delete item: Error "Server Error" 2026/02/03 02:25:47 DEBUG : pacer: Reducing sleep to 10s --- PASS: TestSyncAfterChangingModtimeOnly (414.57s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:1046: Can't check this if no hashes supported 2026/02/03 02:26:07 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 02:26:17 DEBUG : pacer: Reducing sleep to 2.5s --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (29.98s) === RUN TestSyncDoesntUpdateModtime run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:1072: Can't run this test on fs which doesn't support mod time 2026/02/03 02:26:22 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 02:26:24 DEBUG : pacer: Reducing sleep to 625ms --- SKIP: TestSyncDoesntUpdateModtime (7.50s) === RUN TestSyncAfterAddingAFile run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:26:26 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 02:26:28 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 02:26:28 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 02:26:30 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 02:26:30 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 02:26:32 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 7s just to make sure 2026/02/03 02:26:39 DEBUG : empty space: size = 1 OK 2026/02/03 02:26:39 DEBUG : empty space: Sizes identical 2026/02/03 02:26:39 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/03 02:26:39 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:26:39 DEBUG : empty space: Unchanged skipping 2026/02/03 02:26:39 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:26:42 DEBUG : potato: size = 60 OK 2026/02/03 02:26:42 INFO : potato: Copied (new) 2026/02/03 02:26:42 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go: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 (27.07s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:26:55 DEBUG : potato: Removing old object on successful upload 2026/02/03 02:26:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:26:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:26:56 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:26:56 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:26:56 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:26:56 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:26:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:26:57 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:26:57 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:26:57 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:26:58 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:26:58 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:26:58 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:26:58 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:26:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:26:58 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:27:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:27:00 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:27:02 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:27:02 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:286: Retry Put of "potato" to drime root 'rclone-test-pepecuh4kece': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error") 2026/02/03 02:27:08 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/03 02:27:20 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/03 02:27:20 DEBUG : potato: Removing old object on successful upload 2026/02/03 02:27:23 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/03 02:27:26 DEBUG : pacer: Reducing sleep to 640ms 2026/02/03 02:27:27 DEBUG : pacer: Reducing sleep to 320ms 2026/02/03 02:27:27 DEBUG : potato: size = 21 (Local file system at /tmp/rclone660238072) 2026/02/03 02:27:27 DEBUG : potato: size = 60 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 02:27:27 DEBUG : potato: Sizes differ 2026/02/03 02:27:27 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:27:27 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:27:30 DEBUG : pacer: Reducing sleep to 160ms 2026/02/03 02:27:30 DEBUG : potato: Removing old object on successful upload 2026/02/03 02:27:31 DEBUG : pacer: Reducing sleep to 80ms 2026/02/03 02:27:31 DEBUG : potato: size = 21 OK 2026/02/03 02:27:31 INFO : potato: Copied (replaced existing) 2026/02/03 02:27:31 DEBUG : Waiting for deletions to finish 2026/02/03 02:27:31 DEBUG : pacer: Reducing sleep to 40ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:27:32 DEBUG : pacer: Reducing sleep to 20ms 2026/02/03 02:27:32 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 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: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: TestSyncAfterChangingFilesSizeOnly (62.16s) === RUN TestSyncAfterChangingContentsOnly run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:1136: ModTimeNotSupported so forcing file to be a different size 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 02:28:04 DEBUG : potato: size = 21 (Local file system at /tmp/rclone660238072) 2026/02/03 02:28:04 DEBUG : potato: size = 36 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 02:28:04 DEBUG : potato: Sizes differ 2026/02/03 02:28:04 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:28:04 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:28:06 DEBUG : potato: Removing old object on successful upload 2026/02/03 02:28:06 DEBUG : potato: size = 21 OK 2026/02/03 02:28:06 INFO : potato: Copied (replaced existing) 2026/02/03 02:28:06 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 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: TestSyncAfterChangingContentsOnly (32.45s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:28:33 DEBUG : empty space: Need to transfer - File not found at Destination 2026/02/03 02:28:33 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 02:28:33 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2026/02/03 02:28:33 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2026/02/03 02:28:33 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:28:33 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:28:33 DEBUG : Waiting for deletions to finish 2026/02/03 02:28:33 NOTICE: potato: Skipped delete as --dry-run is set (size 21) 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:1172 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileDryRun 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:1172 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterRemovingAFileAndAddingAFileDryRun 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: TestSyncAfterRemovingAFileAndAddingAFileDryRun Messages: listing wrong, want 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/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: TestSyncAfterRemovingAFileAndAddingAFileDryRun 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: TestSyncAfterRemovingAFileAndAddingAFileDryRun Messages: Unexpected file "potato" --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileDryRun (25.31s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:28:57 DEBUG : potato: Removing old object on successful upload 2026/02/03 02:28:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:28:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:28:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:28:58 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:28:58 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:28:58 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:28:58 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:28:58 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:28:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:28:58 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:28:59 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:28:59 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:28:59 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:28:59 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:29:00 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:29:00 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:29:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:29:01 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:29:04 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:29:04 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:286: Retry Put of "potato" to drime root 'rclone-test-pepecuh4kece': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error") 2026/02/03 02:29:09 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/03 02:29:20 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/03 02:29:20 DEBUG : potato: Removing old object on successful upload 2026/02/03 02:29:24 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/03 02:29:26 DEBUG : pacer: Reducing sleep to 640ms 2026/02/03 02:29:32 DEBUG : pacer: Reducing sleep to 320ms 2026/02/03 02:29:32 DEBUG : pacer: Reducing sleep to 160ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:29:33 DEBUG : pacer: Reducing sleep to 80ms 2026/02/03 02:29:33 DEBUG : pacer: Reducing sleep to 40ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:29:36 DEBUG : pacer: Reducing sleep to 20ms 2026/02/03 02:29:36 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: listing wrong, want empty space (1), potato (21) got potato (21) fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: 1 objects not found 2026/02/03 02:29:40 DEBUG : empty space: size = 1 OK 2026/02/03 02:29:40 DEBUG : empty space: Sizes identical 2026/02/03 02:29:40 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 02:29:40 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:29:40 DEBUG : empty space: Unchanged skipping 2026/02/03 02:29:40 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:29:42 DEBUG : potato2: size = 60 OK 2026/02/03 02:29:42 INFO : potato2: Copied (new) 2026/02/03 02:29:42 DEBUG : Waiting for deletions to finish 2026/02/03 02:29:42 INFO : potato: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1190 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: listing wrong, want empty space (1), potato2 (60) got empty space (1), potato (21) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1190 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: Unexpected file "potato" fstest.go:203: Not found "potato2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1190 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: listing wrong, want got empty space (1), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: Unexpected file "potato2" --- FAIL: TestSyncAfterRemovingAFileAndAddingAFile (69.01s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:30:10 INFO : d: Making directory 2026/02/03 02:30:10 INFO : d/e: Making directory 2026/02/03 02:30:12 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/03 02:30:12 DEBUG : c/non empty space: size = 5 OK 2026/02/03 02:30:12 DEBUG : c/non empty space: Sizes identical 2026/02/03 02:30:12 DEBUG : c/non empty space: Unchanged skipping 2026/02/03 02:30:13 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:30:13 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:30:16 DEBUG : a/potato2: size = 60 OK 2026/02/03 02:30:16 INFO : a/potato2: Copied (new) 2026/02/03 02:30:16 DEBUG : Waiting for deletions to finish 2026/02/03 02:30:16 INFO : b/potato: Deleted 2026/02/03 02:30:16 INFO : d/e: Removing directory 2026/02/03 02:30:17 INFO : d: Removing directory 2026/02/03 02:30:17 DEBUG : d: Failed to Rmdir: directory not empty 2026/02/03 02:30:17 INFO : b: Removing directory 2026/02/03 02:30:17 DEBUG : b: Failed to Rmdir: directory not empty 2026/02/03 02:30:17 DEBUG : drime root 'rclone-test-pepecuh4kece': failed to delete 2 directories 2026/02/03 02:30:17 DEBUG : drime root 'rclone-test-pepecuh4kece': deleted 1 directories fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1248 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1262 Error: Not equal: expected: []string{"a", "c"} actual : []string{"a", "b", "c", "d"} Diff: --- Expected +++ Actual @@ -1,4 +1,6 @@ -([]string) (len=2) { +([]string) (len=4) { (string) (len=1) "a", - (string) (len=1) "c" + (string) (len=1) "b", + (string) (len=1) "c", + (string) (len=1) "d" } Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: directories run.go:130: removing dir "c" failed - try 1/3: directory not empty run.go:130: removing dir "c" failed - try 2/3: directory not empty run.go:130: removing dir "c" failed - try 3/3: directory not empty run.go:133: removing dir "c" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"c"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=1) "c" } Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: directories --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDir (43.56s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:30:50 INFO : d: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go: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:1285 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: listing wrong, want b/potato (21), c/non empty space (5) got b/potato (21) fstest.go:203: Not found "c/non 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/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1285 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: 1 objects not found 2026/02/03 02:31:00 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/03 02:31:00 DEBUG : c/non empty space: size = 5 OK 2026/02/03 02:31:00 DEBUG : c/non empty space: Sizes identical 2026/02/03 02:31:00 DEBUG : c/non empty space: Unchanged skipping 2026/02/03 02:31:00 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:31:00 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:31:04 DEBUG : a/potato2: size = 60 OK 2026/02/03 02:31:04 INFO : a/potato2: Copied (new) 2026/02/03 02:31:04 ERROR : drime root 'rclone-test-pepecuh4kece': not deleting files as there were IO errors 2026/02/03 02:31:04 ERROR : drime root 'rclone-test-pepecuh4kece': 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 run.go:130: removing dir "b" failed - try 3/3: directory not empty run.go:133: removing dir "b" 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{"b", "c"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=1) "b", + (string) (len=1) "c" } Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: directories --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (40.26s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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 02:31:47 DEBUG : Waiting for deletions to finish 2026/02/03 02:31:47 DEBUG : empty space: size = 1 OK 2026/02/03 02:31:47 DEBUG : empty space: Sizes identical 2026/02/03 02:31:47 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 02:31:47 DEBUG : empty space: Unchanged skipping 2026/02/03 02:31:48 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:31:48 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:31:48 INFO : potato: Deleted 2026/02/03 02:31:49 DEBUG : potato2: size = 60 OK 2026/02/03 02:31:49 INFO : potato2: Copied (new) 2026/02/03 02:31:49 INFO : c: Removing directory 2026/02/03 02:31:50 INFO : b: Removing directory 2026/02/03 02:31:50 DEBUG : drime root 'rclone-test-pepecuh4kece': 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: TestSyncDeleteDuring 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: TestSyncDeleteDuring 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: TestSyncDeleteDuring Messages: Unexpected file "potato2" --- FAIL: TestSyncDeleteDuring (35.47s) === RUN TestSyncDeleteBefore run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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 2026/02/03 02:32:16 DEBUG : Waiting for deletions to finish 2026/02/03 02:32:17 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:32:17 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:32:17 INFO : potato: Deleted 2026/02/03 02:32:18 DEBUG : empty space: size = 1 OK 2026/02/03 02:32:18 DEBUG : empty space: Sizes identical 2026/02/03 02:32:18 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 02:32:18 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:32:18 DEBUG : empty space: Unchanged skipping 2026/02/03 02:32:18 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:32:22 DEBUG : potato2: size = 60 OK 2026/02/03 02:32:22 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: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 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:1357 Error: Should be true Test: TestSyncDeleteBefore 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: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: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: TestSyncDeleteBefore 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: TestSyncDeleteBefore 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: TestSyncDeleteBefore Messages: Unexpected file "potato2" --- FAIL: TestSyncDeleteBefore (40.22s) === RUN TestCopyDeleteBefore run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:32:42 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 02:32:42 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:32:42 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:32:46 DEBUG : potato2: size = 19 OK 2026/02/03 02:32:46 INFO : potato2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1379 Error: Should be true Test: TestCopyDeleteBefore Messages: listing wrong, want potato (21), potato2 (19) got potato (21) fstest.go:203: Not found "potato2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1379 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyDeleteBefore Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyDeleteBefore Messages: listing wrong, want got potato (21), potato2 (19) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyDeleteBefore Messages: Unexpected file "potato" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyDeleteBefore Messages: Unexpected file "potato2" --- FAIL: TestCopyDeleteBefore (23.94s) === RUN TestSyncWithExclude run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:33:06 DEBUG : potato2: Removing old object on successful upload 2026/02/03 02:33:06 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:33:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:33:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:33:07 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:33:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:33:07 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:33:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:33:07 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:33:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:33:08 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:33:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:33:08 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:33:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:33:08 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:33:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:33:09 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:33:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:33:10 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:33:13 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:33:13 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:286: Retry Put of "potato2" to drime root 'rclone-test-pepecuh4kece': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error") 2026/02/03 02:33:18 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/03 02:33:29 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/03 02:33:29 DEBUG : potato2: Removing old object on successful upload 2026/02/03 02:33:33 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/03 02:33:36 DEBUG : pacer: Reducing sleep to 640ms 2026/02/03 02:33:41 DEBUG : pacer: Reducing sleep to 320ms 2026/02/03 02:33:41 DEBUG : pacer: Reducing sleep to 160ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:33:42 DEBUG : pacer: Reducing sleep to 80ms 2026/02/03 02:33:43 DEBUG : pacer: Reducing sleep to 40ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:33:45 DEBUG : pacer: Reducing sleep to 20ms 2026/02/03 02:33:45 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1390 Error: Should be true Test: TestSyncWithExclude Messages: listing wrong, want empty space (1), potato2 (60) got potato2 (60) 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:1390 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncWithExclude Messages: 1 objects not found 2026/02/03 02:33:49 DEBUG : enormous: Excluded (Size Filter) 2026/02/03 02:33:49 DEBUG : enormous: Excluded 2026/02/03 02:33:49 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 02:33:49 DEBUG : potato2: Excluded 2026/02/03 02:33:50 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 02:33:50 DEBUG : potato2: Excluded 2026/02/03 02:33:50 DEBUG : empty space: size = 1 OK 2026/02/03 02:33:50 DEBUG : empty space: Sizes identical 2026/02/03 02:33:50 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:33:50 DEBUG : empty space: Unchanged skipping 2026/02/03 02:33:50 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:33:50 DEBUG : Waiting for deletions to finish 2026/02/03 02:33:50 INFO : There was nothing to transfer 2026/02/03 02:33:50 DEBUG : enormous: Excluded (Size Filter) 2026/02/03 02:33:50 DEBUG : enormous: Excluded 2026/02/03 02:33:50 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 02:33:50 DEBUG : potato2: Excluded 2026/02/03 02:33:50 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 02:33:50 DEBUG : potato2: Excluded 2026/02/03 02:33:50 DEBUG : Local file system at /tmp/rclone660238072: Waiting for checks to finish 2026/02/03 02:33:50 DEBUG : empty space: size = 1 OK 2026/02/03 02:33:50 DEBUG : empty space: Sizes identical 2026/02/03 02:33:50 DEBUG : empty space: Unchanged skipping 2026/02/03 02:33:50 DEBUG : Local file system at /tmp/rclone660238072: Waiting for transfers to finish 2026/02/03 02:33:50 DEBUG : Waiting for deletions to finish 2026/02/03 02:33: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: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: TestSyncWithExclude 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: TestSyncWithExclude 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: TestSyncWithExclude Messages: Unexpected file "potato2" --- FAIL: TestSyncWithExclude (55.92s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", 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 02:34:16 DEBUG : enormous: Excluded (Size Filter) 2026/02/03 02:34:16 DEBUG : enormous: Excluded 2026/02/03 02:34:16 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 02:34:16 DEBUG : potato2: Excluded 2026/02/03 02:34:17 DEBUG : empty space: size = 1 OK 2026/02/03 02:34:17 DEBUG : empty space: Sizes identical 2026/02/03 02:34:17 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:34:17 DEBUG : empty space: Unchanged skipping 2026/02/03 02:34:17 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:34:17 DEBUG : Waiting for deletions to finish 2026/02/03 02:34:17 INFO : enormous: Deleted 2026/02/03 02:34:17 INFO : potato2: Deleted 2026/02/03 02:34:17 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 2026/02/03 02:34:28 DEBUG : empty space: size = 1 OK 2026/02/03 02:34:28 DEBUG : empty space: Sizes identical 2026/02/03 02:34:28 DEBUG : empty space: Unchanged skipping 2026/02/03 02:34:28 DEBUG : Local file system at /tmp/rclone660238072: Waiting for checks to finish 2026/02/03 02:34:28 DEBUG : Local file system at /tmp/rclone660238072: Waiting for transfers to finish 2026/02/03 02:34:28 DEBUG : Waiting for deletions to finish 2026/02/03 02:34:28 INFO : enormous: Deleted 2026/02/03 02:34:28 INFO : potato2: Deleted 2026/02/03 02:34:28 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: TestSyncWithExcludeAndDeleteExcluded (39.64s) === RUN TestSyncWithUpdateOlder run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:1454: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncWithUpdateOlder (0.52s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1497: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1497: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:1569: Can track renames: false 2026/02/03 02:34:40 ERROR : drime root 'rclone-test-pepecuh4kece': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/03 02:34:40 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/03 02:34:40 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/03 02:34:40 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:34:40 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:34:42 DEBUG : potato: size = 14 OK 2026/02/03 02:34:42 INFO : potato: Copied (new) 2026/02/03 02:34:43 DEBUG : yam: size = 11 OK 2026/02/03 02:34:43 INFO : yam: Copied (new) 2026/02/03 02:34:43 DEBUG : Waiting for deletions to finish 2026/02/03 02:34:43 ERROR : drime root 'rclone-test-pepecuh4kece': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/03 02:34:44 DEBUG : potato: size = 14 OK 2026/02/03 02:34:44 DEBUG : potato: Sizes identical 2026/02/03 02:34:44 DEBUG : yaml: Need to transfer - File not found at Destination 2026/02/03 02:34:44 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:34:44 DEBUG : potato: Unchanged skipping 2026/02/03 02:34:44 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:34:45 DEBUG : yaml: size = 11 OK 2026/02/03 02:34:45 INFO : yaml: Copied (new) 2026/02/03 02:34:45 DEBUG : Waiting for deletions to finish 2026/02/03 02:34:46 INFO : yam: 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:1590 Error: Should be true Test: TestSyncWithTrackRenames Messages: listing wrong, want potato (14), yaml (11) got potato (14), 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/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:1590 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncWithTrackRenames Messages: Unexpected file "yam" fstest.go:203: Not found "yaml" 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:1590 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncWithTrackRenames 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: TestSyncWithTrackRenames Messages: listing wrong, want got potato (14), yaml (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: TestSyncWithTrackRenames 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: TestSyncWithTrackRenames Messages: Unexpected file "yaml" --- FAIL: TestSyncWithTrackRenames (24.17s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:1641: Can track renames: false 2026/02/03 02:35:04 ERROR : drime root 'rclone-test-pepecuh4kece': Ignoring --track-renames as either the source or destination do not support modtime 2026/02/03 02:35:05 DEBUG : potato: size = 14 OK 2026/02/03 02:35:05 DEBUG : potato: Sizes identical 2026/02/03 02:35:05 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/03 02:35:05 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:35:05 DEBUG : potato: Unchanged skipping 2026/02/03 02:35:05 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:35:06 DEBUG : yam: size = 11 OK 2026/02/03 02:35:06 INFO : yam: Copied (new) 2026/02/03 02:35:06 DEBUG : Waiting for deletions to finish 2026/02/03 02:35:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:35:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:35:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:35:07 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:35:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:35:07 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:35:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:35:08 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:35:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:35:08 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:35:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:35:08 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:35:09 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:35:09 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:35:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:35:10 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:35:11 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:35:11 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:35:13 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:35:13 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/03 02:35:13 ERROR : yaml: Couldn't delete: failed to delete item: Error "Server Error" 2026/02/03 02:35:13 ERROR : drime root 'rclone-test-pepecuh4kece': not deleting directories as there were IO errors sync_test.go:1648: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1648 Error: Received unexpected error: failed to delete 1 files Test: TestSyncWithTrackRenamesStrategyModtime 2026/02/03 02:35:18 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/03 02:35:29 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/03 02:35:34 DEBUG : pacer: Reducing sleep to 1.28s --- FAIL: TestSyncWithTrackRenamesStrategyModtime (29.46s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" sync_test.go:1680: Can track renames: false 2026/02/03 02:35:36 DEBUG : pacer: Reducing sleep to 640ms 2026/02/03 02:35:36 INFO : drime root 'rclone-test-pepecuh4kece': Making map for --track-renames 2026/02/03 02:35:36 INFO : drime root 'rclone-test-pepecuh4kece': Finished making map for --track-renames 2026/02/03 02:35:36 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:35:36 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/02/03 02:35:36 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for renames to finish 2026/02/03 02:35:36 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2026/02/03 02:35:36 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:35:38 DEBUG : pacer: Reducing sleep to 320ms 2026/02/03 02:35:38 DEBUG : pacer: Reducing sleep to 160ms 2026/02/03 02:35:38 DEBUG : pacer: Reducing sleep to 80ms 2026/02/03 02:35:39 DEBUG : pacer: Reducing sleep to 40ms 2026/02/03 02:35:42 DEBUG : pacer: Reducing sleep to 20ms 2026/02/03 02:35:42 DEBUG : potato: size = 14 OK 2026/02/03 02:35:42 INFO : potato: Copied (new) 2026/02/03 02:35:43 DEBUG : pacer: Reducing sleep to 10ms 2026/02/03 02:35:43 DEBUG : sub/yam: size = 11 OK 2026/02/03 02:35:43 INFO : sub/yam: Copied (new) 2026/02/03 02:35:43 DEBUG : Waiting for deletions to finish 2026/02/03 02:35:44 DEBUG : potato: size = 14 OK 2026/02/03 02:35:44 DEBUG : potato: Sizes identical 2026/02/03 02:35:44 DEBUG : potato: Unchanged skipping 2026/02/03 02:35:44 INFO : drime root 'rclone-test-pepecuh4kece': Making map for --track-renames 2026/02/03 02:35:44 INFO : drime root 'rclone-test-pepecuh4kece': Finished making map for --track-renames 2026/02/03 02:35:44 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:35:44 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for renames to finish 2026/02/03 02:35:45 ERROR : sub/yam: Couldn't move: object not found 2026/02/03 02:35:45 DEBUG : yam: Failed to rename to "sub/yam": object not found 2026/02/03 02:35:45 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/02/03 02:35:45 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:35:50 DEBUG : yam: size = 11 OK 2026/02/03 02:35:50 INFO : yam: Copied (new) 2026/02/03 02:35:50 ERROR : drime root 'rclone-test-pepecuh4kece': not deleting files as there were IO errors 2026/02/03 02:35:50 ERROR : drime root 'rclone-test-pepecuh4kece': 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 (30.65s) === RUN TestServerSideMoveLocal run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:36:04 DEBUG : Creating backend with remote "/tmp/rclone660238072/dir1" 2026/02/03 02:36:04 DEBUG : Config file has changed externally - reloading 2026/02/03 02:36:04 DEBUG : Creating backend with remote "/tmp/rclone660238072/dir2" 2026/02/03 02:36:04 DEBUG : Local file system at /tmp/rclone660238072/dir2: Using server-side directory move 2026/02/03 02:36:04 INFO : Local file system at /tmp/rclone660238072/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/03 02:36:04 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/03 02:36:04 INFO : file1.txt: Moved (server-side) 2026/02/03 02:36:04 DEBUG : Local file system at /tmp/rclone660238072/dir2: Waiting for checks to finish 2026/02/03 02:36:04 DEBUG : Local file system at /tmp/rclone660238072/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (1.93s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:36:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 02:36:06 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/03 02:36:06 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:36:06 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:36:11 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 02:36:11 INFO : sub dir/hello world: Copied (new) 2026/02/03 02:36:11 INFO : sub dir/hello world: Deleted 2026/02/03 02:36:13 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/03 02:36:13 INFO : nested/sub dir/file: Copied (new) 2026/02/03 02:36:13 INFO : nested/sub dir/file: Deleted 2026/02/03 02:36:13 ERROR : Local file system at /tmp/rclone660238072: not deleting directories as there were IO errors sync_test.go:1819: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1819 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (7.37s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:36:14 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/03 02:36:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 02:36:14 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:36:14 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:36:16 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/03 02:36:16 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 02:36:16 INFO : sub dir/hello world: Copied (new) 2026/02/03 02:36:16 INFO : sub dir/hello world: Deleted 2026/02/03 02:36:17 DEBUG : nested/sub dir/file: Removing old object on successful upload 2026/02/03 02:36:17 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/03 02:36:17 INFO : nested/sub dir/file: Copied (new) 2026/02/03 02:36:17 INFO : nested/sub dir/file: Deleted 2026/02/03 02:36:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:36:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:36:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:36:19 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:36:19 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:36:19 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:36:20 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:36:20 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:36:20 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:36:20 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:36:20 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:36:20 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:36:20 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:36:20 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:36:21 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:36:21 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:36:22 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:36:22 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:36:25 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:36:25 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 02:36:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:36:30 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:36:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:37:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:37:20 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:37:40 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:38:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:38:20 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:38:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:39:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:39:20 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 02:39:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:40:00 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:40:21 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:40:40 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:41:00 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:41:20 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:41:40 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:42:00 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:42:21 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:42:40 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 02:43:00 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:43:20 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:43:40 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:44:00 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:44:21 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:44:41 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:45:01 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:45:21 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:45:41 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:46:01 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "nested/sub dir/file" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 02:46:21 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:46:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:47:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:47:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:47:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:48:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:48:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:48:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:49:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:49:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "nested/sub dir/file" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 02:49:41 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:50:01 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:50:21 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:50:41 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:51:01 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:51:21 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:51:41 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:52:01 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:52:21 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:52:41 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "nested/sub dir/file" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "nested/sub dir/file" failed: failed to delete item: Error "Server Error" 2026/02/03 02:53:01 DEBUG : pacer: Reducing sleep to 10s run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty 2026/02/03 02:53:21 DEBUG : pacer: Reducing sleep to 5s run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty 2026/02/03 02:53:31 DEBUG : pacer: Reducing sleep to 2.5s run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty 2026/02/03 02:53:36 DEBUG : pacer: Reducing sleep to 1.25s run.go:130: removing dir "nested/sub dir" failed - try 1/3: directory not empty 2026/02/03 02:53:38 DEBUG : pacer: Reducing sleep to 625ms run.go:130: removing dir "nested/sub dir" failed - try 2/3: directory not empty 2026/02/03 02:53:40 DEBUG : pacer: Reducing sleep to 312.5ms 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 2026/02/03 02:53:42 DEBUG : pacer: Reducing sleep to 156.25ms run.go:130: removing dir "nested" failed - try 1/3: directory not empty 2026/02/03 02:53:43 DEBUG : pacer: Reducing sleep to 78.125ms run.go:130: removing dir "nested" failed - try 2/3: directory not empty 2026/02/03 02:53:44 DEBUG : pacer: Reducing sleep to 39.0625ms run.go:130: removing dir "nested" failed - try 3/3: directory not empty run.go:133: removing dir "nested" failed: directory not empty 2026/02/03 02:53:45 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 02:53:46 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: TestMoveWithoutDeleteEmptySrcDirs Messages: listing wrong, want got nested/sub dir/file (6), 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: TestMoveWithoutDeleteEmptySrcDirs Messages: Unexpected file "sub dir/hello world" 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: TestMoveWithoutDeleteEmptySrcDirs Messages: Unexpected file "nested/sub dir/file" 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", "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: TestMoveWithoutDeleteEmptySrcDirs Messages: directories --- FAIL: TestMoveWithoutDeleteEmptySrcDirs (1061.48s) === RUN TestMoveWithIgnoreExisting run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:53:56 DEBUG : existing-b: Need to transfer - File not found at Destination 2026/02/03 02:53:56 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/03 02:53:56 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:53:56 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:53:58 DEBUG : existing: size = 6 OK 2026/02/03 02:53:58 INFO : existing: Copied (new) 2026/02/03 02:53:58 INFO : existing: Deleted 2026/02/03 02:53:58 DEBUG : existing-b: size = 6 OK 2026/02/03 02:53:58 INFO : existing-b: Copied (new) 2026/02/03 02:53:58 INFO : existing-b: 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 nested/sub dir/file (6), 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:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1873 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "sub dir/hello world" 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:1873 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "nested/sub dir/file" 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", "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: TestMoveWithIgnoreExisting Messages: directories 2026/02/03 02:54:09 DEBUG : existing: Destination exists, skipping 2026/02/03 02:54:09 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2026/02/03 02:54:09 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for checks to finish 2026/02/03 02:54:09 DEBUG : drime root 'rclone-test-pepecuh4kece': Waiting for transfers to finish 2026/02/03 02:54:09 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: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:1898 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got existing (6), existing-b (6), nested/sub dir/file (6), 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:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1898 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "sub dir/hello world" 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:1898 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "nested/sub dir/file" 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", "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: TestMoveWithIgnoreExisting 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 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 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: TestMoveWithIgnoreExisting Messages: directories --- FAIL: TestMoveWithIgnoreExisting (49.12s) === RUN TestServerSideMove run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 02:54:44 DEBUG : Creating backend with remote "TestDrime:rclone-test-vodugeh9juvu" 2026/02/03 02:54:44 DEBUG : Config file has changed externally - reloading 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 sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-pepecuh4kece' -> drime root 'rclone-test-vodugeh9juvu' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:55:13 ERROR : error listing: directory not found fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:55:15 ERROR : error listing: directory not found fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1741 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go: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 02:55:19 DEBUG : drime root 'rclone-test-vodugeh9juvu': Using server-side directory move 2026/02/03 02:55:20 INFO : drime root 'rclone-test-vodugeh9juvu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/03 02:55:20 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 02:55:20 DEBUG : empty space: size = 1 OK 2026/02/03 02:55:20 DEBUG : empty space: Sizes identical 2026/02/03 02:55:20 DEBUG : empty space: Unchanged skipping 2026/02/03 02:55:20 DEBUG : potato3: size = 68 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 02:55:20 DEBUG : potato3: size = 60 (drime root 'rclone-test-vodugeh9juvu') 2026/02/03 02:55:20 DEBUG : potato3: Sizes differ 2026/02/03 02:55:20 INFO : potato3: Deleted 2026/02/03 02:55:20 INFO : empty space: Deleted 2026/02/03 02:55:21 DEBUG : drime root 'rclone-test-vodugeh9juvu': Waiting for checks to finish 2026/02/03 02:55:21 DEBUG : drime root 'rclone-test-vodugeh9juvu': Waiting for transfers to finish 2026/02/03 02:55:21 ERROR : potato2: Couldn't move: object not found 2026/02/03 02:55:21 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 02:55:21 DEBUG : drime root 'rclone-test-vodugeh9juvu': Purge remote 2026/02/03 02:55:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:55:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:55:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:55:22 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:55:22 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:55:22 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:55:23 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:55:23 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:55:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:55:23 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:55:23 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:55:23 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:55:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:55:24 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:55:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:55:24 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:55:26 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:55:26 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:55:28 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:55:28 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 02:55:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:55:33 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:55:43 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:56:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:56:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:56:44 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:57:04 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:57:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:57:44 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:58:04 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:58:24 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 02:58:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:59:04 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:59:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:59:44 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:00:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:00:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:00:44 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:01:04 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:01:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:01:44 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 03:02:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:02:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:02:44 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:03:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:03:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:03:44 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:04:04 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:04:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:04:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:05:04 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 03:05:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:05:44 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:06:04 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:06:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:06:44 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:07:04 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:07:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:07:44 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:08:04 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:08:24 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 03:08:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:09:04 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:09:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:09:44 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:10:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:10:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:10:44 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:11:04 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:11:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:11:44 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 03:12:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:12:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:12:44 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:13:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:13:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:13:44 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:14:04 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:14:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:14:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:15:04 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 03:15:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:15:44 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:16:04 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:16:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:16:44 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:17:04 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:17:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:17:44 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:18:04 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:18:24 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 03:18:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:19:04 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:19:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:19:44 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:20:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:20:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:20:44 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:21:04 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:21:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:21:44 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 03:22:04 DEBUG : pacer: Reducing sleep to 10s 2026/02/03 03:22:25 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 03:22:34 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 03:22:40 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 03:22:42 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 03:22:43 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 03:22:44 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 03:22:44 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 03:22:44 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 03:22:45 DEBUG : pacer: Reducing sleep to 19.53125ms --- FAIL: TestServerSideMove (1680.39s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 03:22:45 DEBUG : Creating backend with remote "TestDrime:rclone-test-kirocir1fara" 2026/02/03 03:22:45 DEBUG : Config file has changed externally - reloading 2026/02/03 03:22:46 DEBUG : pacer: Reducing sleep to 10ms sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-pepecuh4kece' -> drime root 'rclone-test-kirocir1fara' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 03:23:02 ERROR : error listing: directory not found fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 03:23:05 ERROR : error listing: directory not found fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1741 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1925 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1), potato3 (60) got fstest.go:203: Not found "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 03:23:09 DEBUG : empty space: Excluded (Size Filter) 2026/02/03 03:23:09 DEBUG : empty space: Excluded 2026/02/03 03:23:09 DEBUG : empty space: Excluded (Size Filter) 2026/02/03 03:23:09 DEBUG : empty space: Excluded 2026/02/03 03:23:09 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 03:23:09 DEBUG : drime root 'rclone-test-kirocir1fara': Waiting for checks to finish 2026/02/03 03:23:09 DEBUG : potato3: size = 68 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 03:23:09 DEBUG : potato3: size = 60 (drime root 'rclone-test-kirocir1fara') 2026/02/03 03:23:09 DEBUG : potato3: Sizes differ 2026/02/03 03:23:09 DEBUG : drime root 'rclone-test-kirocir1fara': Waiting for transfers to finish 2026/02/03 03:23:10 INFO : potato3: Deleted 2026/02/03 03:23:10 ERROR : potato2: Couldn't move: object not found 2026/02/03 03:23:10 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 03:23:10 DEBUG : drime root 'rclone-test-kirocir1fara': Purge remote 2026/02/03 03:23:11 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:23:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 03:23:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:23:12 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 03:23:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:23:12 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 03:23:12 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:23:12 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 03:23:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:23:12 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 03:23:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:23:12 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 03:23:13 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:23:13 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 03:23:14 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:23:14 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 03:23:15 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:23:15 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 03:23:18 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 03:23:18 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 03:23:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:23:22 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 03:23:33 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:23:53 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:24:13 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:24:33 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:24:53 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:25:13 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:25:33 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:25:53 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:26:13 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 03:26:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:27:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:27:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:27:33 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:27:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:27:53 DEBUG : pacer: low level retry 5/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 03:28:13 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:28:45 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:28:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:29:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:29:13 DEBUG : pacer: low level retry 9/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 03:29:33 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 03:30:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:30:13 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:30:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:31:13 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:31:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:31:13 DEBUG : pacer: low level retry 5/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 03:31:33 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:31:53 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:32:13 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:32:33 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:32:53 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 03:33:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:33:13 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 03:33:33 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:33:33 DEBUG : pacer: low level retry 2/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 03:33:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:33:53 DEBUG : pacer: Reducing sleep to 10s run.go:130: removing file "potato3" failed - try 2/3: failed to delete item: 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 03:33:53 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: 9c7ede9078a092b7\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" 2026/02/03 03:34:13 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:34:13 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 03:34:23 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:34:43 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:35:03 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:35:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:35:43 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:36:03 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:36:23 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:36:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:37:04 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 03:37:24 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestServerSideMoveWithFilter (878.93s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "drime root 'rclone-test-pepecuh4kece'", Local "Local file system at /tmp/rclone660238072", Modify Window "876000h0m0s" 2026/02/03 03:37:24 DEBUG : Creating backend with remote "TestDrime:rclone-test-lokihol0cuna" 2026/02/03 03:37:24 DEBUG : Config file has changed externally - reloading 2026/02/03 03:37:43 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 03:37:57 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 03:37:58 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 03:38:07 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 03:38:07 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 03:38:10 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 03:38:10 INFO : tomatoDir: Making directory 2026/02/03 03:38:11 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 03:38:11 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 03:38:11 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 03:38:12 DEBUG : pacer: Reducing sleep to 10ms sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-pepecuh4kece' -> drime root 'rclone-test-lokihol0cuna' 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:38:29 DEBUG : drime root 'rclone-test-lokihol0cuna': Using server-side directory move 2026/02/03 03:38:29 INFO : drime root 'rclone-test-lokihol0cuna': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/03 03:38:29 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 03:38:29 DEBUG : empty space: size = 1 OK 2026/02/03 03:38:29 DEBUG : empty space: Sizes identical 2026/02/03 03:38:29 DEBUG : potato3: size = 68 (drime root 'rclone-test-pepecuh4kece') 2026/02/03 03:38:29 DEBUG : empty space: Unchanged skipping 2026/02/03 03:38:29 DEBUG : potato3: size = 60 (drime root 'rclone-test-lokihol0cuna') 2026/02/03 03:38:29 DEBUG : potato3: Sizes differ 2026/02/03 03:38:29 DEBUG : drime root 'rclone-test-lokihol0cuna': Waiting for checks to finish 2026/02/03 03:38:29 INFO : empty space: Deleted 2026/02/03 03:38:29 DEBUG : drime root 'rclone-test-lokihol0cuna': Waiting for transfers to finish 2026/02/03 03:38:29 INFO : potato3: Deleted 2026/02/03 03:38:30 ERROR : potato2: Couldn't move: object not found 2026/02/03 03:38:30 ERROR : potato3: Couldn't move: object not found 2026/02/03 03:38:30 ERROR : drime root 'rclone-test-pepecuh4kece': not deleting directories as there were IO errors 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:1932 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2026/02/03 03:38:30 DEBUG : drime root 'rclone-test-lokihol0cuna': Purge remote 2026/02/03 03:38:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:38:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 03:38:31 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:38:31 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 03:38:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:38:32 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 03:38:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:38:32 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 03:38:32 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:38:32 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 03:38:33 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:38:33 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 03:38:33 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:38:33 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 03:38:34 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:38:34 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 03:38:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:38:35 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 03:38:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 03:38:38 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 03:38:43 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:38:43 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 03:38:53 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:39:13 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:39:33 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:39:53 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:40:13 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:40:33 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:40:53 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:41:13 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:41:33 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 03:41:53 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:42:13 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:42:33 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:42:53 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:43:13 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:43:33 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:43:53 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:44:13 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:44:33 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:44:53 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 03:45:13 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") panic: test timed out after 2h0m0s running tests: TestServerSideMoveDeleteEmptySourceDirs (14m34s) goroutine 11271 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 14 minutes]: testing.(*T).Run(0xc000472700, {0x29a88e0?, 0xc00006bad8?}, 0x2ab0ce0) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc000472700) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc000472700, 0xc00006bc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc0006cf0c8, {0x44671e0, 0x6c, 0x6c}, {0x84a160?, 0x44a31f0?, 0x447f440?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0007fe960) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0007fe960) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45 main.main() _testmain.go:261 +0xa9 goroutine 35 [syscall, 120 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 3922 [chan receive, 103 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 3899 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 36 [chan receive, 120 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 11049 [sync.WaitGroup.Wait, 14 minutes]: sync.runtime_SemacquireWaitGroup(0xc00059b7d0?) /usr/local/go/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0x2e502b8?) /usr/local/go/src/sync/waitgroup.go:118 +0x48 github.com/rclone/rclone/fs/walk.walk({0x2e502b8, 0x44a2c60}, {0x2e67310, 0xc0007fea00}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc000272630, 0x2ab1b78) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:453 +0x355 github.com/rclone/rclone/fs/walk.walkListDirSorted(...) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:349 github.com/rclone/rclone/fs/walk.Walk({0x2e502b8, 0x44a2c60}, {0x2e67310, 0xc0007fea00}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc000272630) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:76 +0x2a5 github.com/rclone/rclone/fs/walk.listRwalk({0x2e502b8, 0x44a2c60}, {0x2e67310, 0xc0007fea00}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc000272570) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:170 +0x10d github.com/rclone/rclone/fs/walk.ListR({0x2e502b8, 0x44a2c60}, {0x2e67310, 0xc0007fea00}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc000272570) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:160 +0x236 github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:155 +0x107 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc0004480e0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 +0x1c testing.(*common).Cleanup.func1() /usr/local/go/src/testing/testing.go:1211 +0x106 testing.(*common).runCleanup(0xc000b761c0, 0xc00062f340?) /usr/local/go/src/testing/testing.go:1445 +0xea testing.tRunner.func2() /usr/local/go/src/testing/testing.go:1786 +0x25 runtime.Goexit() /usr/local/go/src/runtime/panic.go:636 +0x5e testing.(*common).FailNow(0xc000b761c0) /usr/local/go/src/testing/testing.go:1041 +0x4a github.com/stretchr/testify/require.NoError({0x2e3a190, 0xc000b761c0}, {0x2e25dc0, 0x43e9230}, {0x0, 0x0, 0x0}) /home/rclone/go/pkg/mod/github.com/stretchr/testify@v1.11.1/require/require.go:1401 +0xca github.com/rclone/rclone/fs/sync.testServerSideMove({0x2e502b8, 0x44a2c60}, 0xc000b761c0, 0xc0004480e0, 0x0, 0x1) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747 +0x827 github.com/rclone/rclone/fs/sync.TestServerSideMoveDeleteEmptySourceDirs(0xc000b761c0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1932 +0x48 testing.tRunner(0xc000b761c0, 0x2ab0ce0) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 11219 [select]: net/http.(*Transport).getConn(0xc000622000, 0xc000462b40, {{}, 0x0, {0xc0007d8000, 0x5}, {0xc00082e198, 0x13}, 0x0}) /usr/local/go/src/net/http/transport.go:1520 +0x4ae net/http.(*Transport).roundTrip(0xc000622000, 0xc00058a000) /usr/local/go/src/net/http/transport.go:685 +0xb57 net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000480f00, 0xc00058a000) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x345 net/http.send(0xc00058a000, {0x2e27a80, 0xc000480f00}, {0x1?, 0x76b1e611e308?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc0004c0f30, 0xc00058a000, {0x0?, 0xd?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc0004c0f30, 0xc00058a000) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc000463ea0, {0x2e502b8, 0x44a2c60}, 0xc0005f1770) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:356 +0xd67 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc000463ea0, {0x2e502b8, 0x44a2c60}, 0xc00018adc0?, {0x2293320?, 0xc00072a120?}, {0x2293360, 0xc0002486c0}, 0xb1b84c4721ba917a?, 0x2ab24a0, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x40b github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:522 github.com/rclone/rclone/backend/drime.(*Fs).deleteObject.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:704 +0x75 github.com/rclone/rclone/fs.pacerInvoker(0x2, 0xa, 0x2e26680?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc000480f60, 0xc0009181c0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0xd2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc000480f60, 0xc0009181c0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:235 +0x93 github.com/rclone/rclone/backend/drime.(*Fs).deleteObject(0xc0007fea00, {0x2e502b8, 0x44a2c60}, {0xc0009041c7, 0x9}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:703 +0x1eb github.com/rclone/rclone/backend/drime.(*Object).Remove(0x18?, {0x2e502b8?, 0x44a2c60?}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:1531 +0x2b github.com/rclone/rclone/fstest.newRunIndividual.func1.1.1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:159 +0x24 github.com/rclone/rclone/fstest.retry(0xc000b761c0, {0xc000614288, 0x17}, 0xc0005f1d28) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:126 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc00072c0c0?, 0x18?, 0x25b8860?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:159 +0x29a github.com/rclone/rclone/fs/walk.listRwalk.func1({0x0?, 0x27f8760?}, {0xc00072c0c0, 0x4, 0x4}, {0x0?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:179 +0x162 github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:415 +0x2e4 created by github.com/rclone/rclone/fs/walk.walk in goroutine 11049 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11215 [select, 14 minutes]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16c created by github.com/rclone/rclone/fs/walk.walk in goroutine 11049 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11217 [select, 14 minutes]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16c created by github.com/rclone/rclone/fs/walk.walk in goroutine 11049 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11213 [select, 14 minutes]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16c created by github.com/rclone/rclone/fs/walk.walk in goroutine 11049 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11218 [select, 14 minutes]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16c created by github.com/rclone/rclone/fs/walk.walk in goroutine 11049 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11216 [select, 14 minutes]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16c created by github.com/rclone/rclone/fs/walk.walk in goroutine 11049 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11272 [runnable]: net/http.(*Transport).queueForIdleConn.gowrap2() /usr/local/go/src/net/http/transport.go:1188 runtime.goexit({}) /usr/local/go/src/runtime/asm_amd64.s:1700 +0x1 created by net/http.(*Transport).queueForIdleConn in goroutine 11219 /usr/local/go/src/net/http/transport.go:1188 +0x48d goroutine 11214 [select, 14 minutes]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16c created by github.com/rclone/rclone/fs/walk.walk in goroutine 11049 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11212 [select, 14 minutes]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16c created by github.com/rclone/rclone/fs/walk.walk in goroutine 11049 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11273 [runnable]: net/http.(*Transport).startDialConnForLocked.func1() /usr/local/go/src/net/http/transport.go:1596 created by net/http.(*Transport).startDialConnForLocked in goroutine 11219 /usr/local/go/src/net/http/transport.go:1596 +0x112 "./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Finished ERROR in 2h1m13.270521055s (try 1/5): exit status 2: Failed [TestCopy TestCopyNoTraverse TestCopyCheckFirst TestCopyWithFilesFromAndNoTraverse TestCopyEmptyDirectories TestCopyNoEmptyDirectories TestSyncEmptyDirectories TestSyncSetDelayedModTimes TestSyncNoEmptyDirectories TestServerSideCopy TestCopyOverSelf TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf TestCopyAfterDelete TestSyncBasedOnCheckSum TestSyncSizeOnly TestSyncIgnoreTimes TestSyncIgnoreExisting TestSyncIgnoreErrors TestSyncAfterAddingAFile TestSyncAfterRemovingAFileAndAddingAFileDryRun TestSyncAfterRemovingAFileAndAddingAFile TestSyncAfterRemovingAFileAndAddingAFileSubDir TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors TestSyncDeleteDuring TestSyncDeleteBefore TestCopyDeleteBefore TestSyncWithExclude TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestMoveWithoutDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMove TestServerSideMoveWithFilter]