"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Starting (try 1/5) 2026/02/04 03:38:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-hohafiz2xeta" 2026/02/04 03:38:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/04 03:38:19 DEBUG : Creating backend with remote "/tmp/rclone3346432228" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === 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-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:38:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:38:20 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2026/02/04 03:38:20 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:38:20 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.50s) === RUN TestCopy run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:38:21 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/04 03:38:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:38:21 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:38:21 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:38:26 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:38:26 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/04 03:38:27 ERROR : error listing: directory not found fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:83 Error: Should be true Test: TestCopy Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:83 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopy Messages: 1 objects not found 2026/02/04 03:38:34 DEBUG : drime root 'rclone-test-hohafiz2xeta': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopy Messages: listing wrong, want got sub dir/hello world (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopy Messages: Unexpected file "sub dir/hello world" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestCopy Messages: directories --- FAIL: TestCopy (27.14s) === RUN TestCopyMetadata run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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: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:98 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:177 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: TestCopyMetadata Messages: directories --- FAIL: TestCopyMetadata (11.18s) === RUN TestCopyMetadataNoEmptyDirs run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.84s) === RUN TestCopyMissingDirectory run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:39:00 DEBUG : Creating backend with remote "/non-existing" 2026/02/04 03:39:00 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2026/02/04 03:39:00 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:39:00 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.80s) === RUN TestCopyNoTraverse run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:39:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:39:01 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:39:01 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:39:05 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:39:05 INFO : sub dir/hello world: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:216 Error: Should be true Test: TestCopyNoTraverse Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:216 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyNoTraverse Messages: 1 objects not found run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go: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: TestCopyNoTraverse 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: TestCopyNoTraverse 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: TestCopyNoTraverse Messages: directories --- FAIL: TestCopyNoTraverse (26.38s) === RUN TestCopyNoTraverseDeadlock run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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: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:222 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: TestCopyNoTraverseDeadlock Messages: directories --- FAIL: TestCopyNoTraverseDeadlock (10.25s) === RUN TestCopyCheckFirst run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:39:37 INFO : drime root 'rclone-test-hohafiz2xeta': Running all checks before starting transfers 2026/02/04 03:39:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:39:38 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:39:38 INFO : drime root 'rclone-test-hohafiz2xeta': Checks finished, now starting transfers 2026/02/04 03:39:38 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:39:41 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:39:41 INFO : sub dir/hello world: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:264 Error: Should be true Test: TestCopyCheckFirst 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:264 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyCheckFirst Messages: 1 objects not found run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go: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: TestCopyCheckFirst 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: TestCopyCheckFirst 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: TestCopyCheckFirst Messages: directories --- FAIL: TestCopyCheckFirst (25.52s) === RUN TestSyncNoTraverse run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:40:03 ERROR : Ignoring --no-traverse with sync 2026/02/04 03:40:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:40:04 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:40:04 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:40:06 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:40:06 INFO : sub dir/hello world: Copied (new) 2026/02/04 03:40: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: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:284 Error: Should be true Test: TestSyncNoTraverse 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:284 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncNoTraverse Messages: 1 objects not found run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go: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: TestSyncNoTraverse 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: TestSyncNoTraverse 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: TestSyncNoTraverse Messages: directories --- FAIL: TestSyncNoTraverse (26.45s) === RUN TestCopyWithDepth run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:40:30 DEBUG : hello world2: Need to transfer - File not found at Destination 2026/02/04 03:40:30 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:40:30 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:40:32 DEBUG : hello world2: size = 12 OK 2026/02/04 03:40:32 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: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:304 Error: Should be true Test: TestCopyWithDepth Messages: listing wrong, want hello world2 (12) got fstest.go:203: Not found "hello world2" 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:304 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyWithDepth 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: TestCopyWithDepth Messages: listing wrong, want got hello world2 (12) 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: TestCopyWithDepth Messages: Unexpected file "hello world2" 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: TestCopyWithDepth Messages: directories --- FAIL: TestCopyWithDepth (21.96s) === RUN TestCopyWithFilesFrom run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:40:52 DEBUG : sub dir: Excluded 2026/02/04 03:40:52 DEBUG : hello world2: Excluded (FilesFrom Filter) 2026/02/04 03:40:52 DEBUG : hello world2: Excluded 2026/02/04 03:40:52 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 03:40:52 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:40:52 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:40:55 DEBUG : potato2: size = 11 OK 2026/02/04 03:40:55 INFO : potato2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:334 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCopyWithFilesFrom Messages: listing wrong, want potato2 (11) got fstest.go:203: Not found "potato2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:334 Error: Not equal: expected: 0 actual : 1 Test: TestCopyWithFilesFrom 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: TestCopyWithFilesFrom 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: TestCopyWithFilesFrom Messages: Unexpected file "potato2" --- FAIL: TestCopyWithFilesFrom (22.16s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:41:14 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:41:14 DEBUG : potato2: size = 11 OK 2026/02/04 03:41:14 DEBUG : potato2: Sizes identical 2026/02/04 03:41:14 DEBUG : potato2: Unchanged skipping 2026/02/04 03:41:14 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:41:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 03:41:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/04 03:41:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 03:41:15 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/04 03:41:16 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 03:41:16 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/04 03:41:16 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 03:41:16 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/04 03:41:16 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 03:41:16 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/04 03:41:16 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 03:41:16 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/04 03:41:17 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 03:41:17 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/04 03:41:17 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 03:41:17 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/04 03:41:19 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 03:41:19 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/04 03:41:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/04 03:41:21 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/04 03:41:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 03:41:26 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 03:41:37 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 03:41:57 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 03:42:17 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 03:42:37 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 03:42:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 03:43:17 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 03:43:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 03:43:57 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 03:44:17 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/04 03:44:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 03:44:57 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 03:45:17 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 03:45:37 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 03:45:57 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 03:46:17 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 03:46:37 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 03:46:57 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 03:47:17 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 03:47:37 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/04 03:47:57 DEBUG : pacer: Reducing sleep to 10s --- PASS: TestCopyWithFilesFromAndNoTraverse (403.35s) === RUN TestCopyEmptyDirectories run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:47:57 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/04 03:47:57 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/04 03:47:57 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/02/04 03:47:57 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/04 03:48:17 DEBUG : pacer: Reducing sleep to 5s 2026/02/04 03:48:17 INFO : sub dir2: Making directory 2026/02/04 03:48:27 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/04 03:48:32 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/04 03:48:32 INFO : sub dir: Making directory 2026/02/04 03:48:34 DEBUG : pacer: Reducing sleep to 625ms 2026/02/04 03:48:36 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/04 03:48:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:48:36 INFO : sub dir2/sub sub dir2: Making directory 2026/02/04 03:48:36 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/04 03:48:36 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/04 03:48:36 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:48:36 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:48:37 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/04 03:48:39 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/04 03:48:39 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:48:39 INFO : sub dir/hello world: Copied (new) 2026/02/04 03:48:39 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: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/04 03:48:48 DEBUG : drime root 'rclone-test-hohafiz2xeta': 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: TestCopyEmptyDirectories Messages: directories --- FAIL: TestCopyEmptyDirectories (67.18s) === RUN TestCopyNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:49:04 INFO : sub dir2: Making directory 2026/02/04 03:49:04 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/04 03:49:04 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/04 03:49:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:49:05 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:49:05 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:49:07 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:49:07 INFO : sub dir/hello world: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 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 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestCopyNoEmptyDirectories Messages: directories --- FAIL: TestCopyNoEmptyDirectories (28.84s) === RUN TestMoveEmptyDirectories run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:49:33 DEBUG : sub dir2: Making directory with metadata 2026/02/04 03:49:33 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/04 03:49:33 INFO : sub dir2: Making directory 2026/02/04 03:49:34 INFO : sub dir: Making directory 2026/02/04 03:49:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:49:35 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:49:35 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:49:40 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:49:40 INFO : sub dir/hello world: Copied (new) 2026/02/04 03:49:40 INFO : sub dir/hello world: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:415 Error: Should be true Test: TestMoveEmptyDirectories 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:415 Error: Not equal: expected: 0 actual : 1 Test: TestMoveEmptyDirectories Messages: 1 objects not found 2026/02/04 03:49:48 DEBUG : drime root 'rclone-test-hohafiz2xeta': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2026/02/04 03:49:49 DEBUG : drime root 'rclone-test-hohafiz2xeta': 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: TestMoveEmptyDirectories 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: TestMoveEmptyDirectories 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: TestMoveEmptyDirectories Messages: directories --- FAIL: TestMoveEmptyDirectories (29.86s) === RUN TestSyncNoUpdateDirModtime run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:438: Skipping test as backend does not support DirSetModTime 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 --- SKIP: TestSyncNoUpdateDirModtime (9.26s) === RUN TestMoveNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:50:12 INFO : sub dir2: Making directory 2026/02/04 03:50:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:50:12 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:50:12 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:50:16 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:50:16 INFO : sub dir/hello world: Copied (new) 2026/02/04 03:50:16 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 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: TestMoveNoEmptyDirectories Messages: directories --- FAIL: TestMoveNoEmptyDirectories (25.86s) === RUN TestSyncEmptyDirectories run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:50:38 DEBUG : sub dir2: Making directory with metadata 2026/02/04 03:50:38 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/04 03:50:38 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/04 03:50:38 INFO : sub dir2: Making directory 2026/02/04 03:50:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:50:39 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:50:39 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:50:42 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:50:42 INFO : sub dir/hello world: Copied (new) 2026/02/04 03:50: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: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 2026/02/04 03:50:51 DEBUG : drime root 'rclone-test-hohafiz2xeta': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestSyncEmptyDirectories Messages: directories --- FAIL: TestSyncEmptyDirectories (28.12s) === RUN TestSyncSetDelayedModTimes run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (1.70s) === RUN TestSyncNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 03:51:08 INFO : sub dir2: Making directory 2026/02/04 03:51:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:51:08 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:51:08 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:51:11 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:51:11 INFO : sub dir/hello world: Copied (new) 2026/02/04 03:51:11 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: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 (26.55s) === RUN TestServerSideCopy run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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/04 03:51:50 DEBUG : Creating backend with remote "TestDrime:rclone-test-zopelub9deve" 2026/02/04 03:51:50 DEBUG : Config file has changed externally - reloading sync_test.go:620: Server side copy (if possible) drime root 'rclone-test-hohafiz2xeta' -> drime root 'rclone-test-zopelub9deve' 2026/02/04 03:51:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:51:52 DEBUG : drime root 'rclone-test-zopelub9deve': Waiting for checks to finish 2026/02/04 03:51:52 DEBUG : drime root 'rclone-test-zopelub9deve': Waiting for transfers to finish 2026/02/04 03:51:55 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:51:55 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/04 03:51:56 DEBUG : drime root 'rclone-test-zopelub9deve': Purge remote run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestServerSideCopy 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: TestServerSideCopy 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: TestServerSideCopy Messages: directories --- FAIL: TestServerSideCopy (35.58s) === RUN TestCopyOverSelf run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:635 Error: Should be true Test: TestCopyOverSelf Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:635 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyOverSelf Messages: 1 objects not found 2026/02/04 03:52:23 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3346432228) 2026/02/04 03:52:23 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 03:52:23 DEBUG : sub dir/hello world: Sizes differ 2026/02/04 03:52:23 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:52:23 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:52:25 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/04 03:52:25 DEBUG : sub dir/hello world: size = 17 OK 2026/02/04 03:52:25 INFO : sub dir/hello world: Copied (replaced existing) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:643 Error: Should be true Test: TestCopyOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (11) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:643 Error: Not equal: expected: 17 actual : 11 Test: TestCopyOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go: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 (40.42s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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/04 03:53:03 DEBUG : Creating backend with remote "TestDrime:rclone-test-dijamos6xixi" 2026/02/04 03:53:03 DEBUG : Config file has changed externally - reloading sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-hohafiz2xeta' -> drime root 'rclone-test-dijamos6xixi' 2026/02/04 03:53:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:53:05 DEBUG : drime root 'rclone-test-dijamos6xixi': Waiting for checks to finish 2026/02/04 03:53:05 DEBUG : drime root 'rclone-test-dijamos6xixi': Waiting for transfers to finish 2026/02/04 03:53:08 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:53:08 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/04 03:53:42 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/04 03:53:44 DEBUG : drime root 'rclone-test-dijamos6xixi': Waiting for checks to finish 2026/02/04 03:53:44 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 03:53:44 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-dijamos6xixi') 2026/02/04 03:53:44 DEBUG : sub dir/hello world: Sizes differ 2026/02/04 03:53:44 DEBUG : drime root 'rclone-test-dijamos6xixi': Waiting for transfers to finish 2026/02/04 03:53:45 DEBUG : sub dir/hello world: Server side copy: removing existing object after successful copy 2026/02/04 03:53:45 DEBUG : sub dir/hello world: size = 17 OK 2026/02/04 03:53:45 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/04 03:53:54 DEBUG : drime root 'rclone-test-dijamos6xixi': Purge remote run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestServerSideCopyOverSelf Messages: listing wrong, want got sub dir/hello world (17) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestServerSideCopyOverSelf Messages: Unexpected file "sub dir/hello world" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestServerSideCopyOverSelf Messages: directories --- FAIL: TestServerSideCopyOverSelf (78.44s) === RUN TestMoveOverSelf run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:679 Error: Should be true Test: TestMoveOverSelf Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:679 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMoveOverSelf Messages: 1 objects not found 2026/02/04 03:54:23 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 03:54:23 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3346432228) 2026/02/04 03:54:23 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 03:54:23 DEBUG : sub dir/hello world: Sizes differ 2026/02/04 03:54:23 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 03:54:27 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/04 03:54:27 DEBUG : sub dir/hello world: size = 17 OK 2026/02/04 03:54:27 INFO : sub dir/hello world: Copied (replaced existing) 2026/02/04 03:54:27 INFO : sub dir/hello world: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:688 Error: Should be true Test: TestMoveOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (11) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:688 Error: Not equal: expected: 17 actual : 11 Test: TestMoveOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestMoveOverSelf Messages: listing wrong, want got sub dir/hello world (17) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestMoveOverSelf Messages: Unexpected file "sub dir/hello world" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestMoveOverSelf Messages: directories --- FAIL: TestMoveOverSelf (42.37s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:696 Error: Should be true Test: TestServerSideMoveOverSelf Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:696 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveOverSelf Messages: 1 objects not found 2026/02/04 03:55:05 DEBUG : Creating backend with remote "TestDrime:rclone-test-huyeqod5lafi" 2026/02/04 03:55:05 DEBUG : Config file has changed externally - reloading sync_test.go:701: Server side copy (if possible) drime root 'rclone-test-hohafiz2xeta' -> drime root 'rclone-test-huyeqod5lafi' 2026/02/04 03:55:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 03:55:07 DEBUG : drime root 'rclone-test-huyeqod5lafi': Waiting for checks to finish 2026/02/04 03:55:07 DEBUG : drime root 'rclone-test-huyeqod5lafi': Waiting for transfers to finish 2026/02/04 03:55:09 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 03:55:09 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/04 03:55:13 DEBUG : sub dir/hello world: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/04 03:55:26 DEBUG : drime root 'rclone-test-huyeqod5lafi': Using server-side directory move 2026/02/04 03:55:26 INFO : drime root 'rclone-test-huyeqod5lafi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/04 03:55:26 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 03:55:26 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-huyeqod5lafi') 2026/02/04 03:55:26 DEBUG : sub dir/hello world: Sizes differ 2026/02/04 03:55:26 DEBUG : drime root 'rclone-test-huyeqod5lafi': Waiting for checks to finish 2026/02/04 03:55:26 DEBUG : drime root 'rclone-test-huyeqod5lafi': Waiting for transfers to finish 2026/02/04 03:55:27 INFO : sub dir/hello world: Deleted 2026/02/04 03:55:27 ERROR : sub dir/hello world: Couldn't move: object not found sync_test.go:714: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:714 Error: Received unexpected error: object not found Test: TestServerSideMoveOverSelf 2026/02/04 03:55:27 DEBUG : drime root 'rclone-test-huyeqod5lafi': Purge remote 2026/02/04 03:55:29 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 03:55:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/04 03:55:29 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 03:55:29 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/04 03:55:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 03:55:29 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/04 03:55:29 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 03:55:29 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/04 03:55:29 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 03:55:29 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/04 03:55:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 03:55:30 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/04 03:55:30 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 03:55:30 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/04 03:55:31 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 03:55:31 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/04 03:55:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 03:55:32 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/04 03:55:35 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/04 03:55:35 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/04 03:55:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 03:55:40 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 03:55:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 03:56:10 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 03:56:30 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 03:56:50 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 03:57:10 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 03:57:30 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 03:57:50 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 03:58:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 03:58:30 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/04 03:58:50 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 03:59:10 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 03:59:30 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 03:59:50 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:00:10 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:00:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:00:50 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:01:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:01:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:01:50 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/04 04:02:10 DEBUG : pacer: Reducing sleep to 10s 2026/02/04 04:02:30 DEBUG : pacer: Reducing sleep to 5s 2026/02/04 04:02:40 DEBUG : pacer: Reducing sleep to 2.5s --- FAIL: TestServerSideMoveOverSelf (469.22s) === RUN TestCopyAfterDelete run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:02:45 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/04 04:02:48 DEBUG : pacer: Reducing sleep to 625ms 2026/02/04 04:02:49 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/04 04:02:53 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/04 04:02:53 ERROR : error listing: directory not found 2026/02/04 04:02:53 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/04 04:02:53 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/04 04:02:53 INFO : Local file system at /tmp/rclone3346432228: Making directory 2026/02/04 04:02:54 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/04 04:02:54 DEBUG : pacer: Reducing sleep to 10ms 2026/02/04 04:02:54 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:02:54 DEBUG : drime root 'rclone-test-hohafiz2xeta': 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 (28.15s) === RUN TestCopyRedownload run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:03:18 DEBUG : Added delayed dir = "sub dir", newDst= 2026/02/04 04:03:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 04:03:19 DEBUG : Local file system at /tmp/rclone3346432228: Waiting for checks to finish 2026/02/04 04:03:19 DEBUG : Local file system at /tmp/rclone3346432228: Waiting for transfers to finish 2026/02/04 04:03:19 DEBUG : sub dir/hello world.9076d4bf.partial: size = 11 OK 2026/02/04 04:03:19 DEBUG : sub dir/hello world.9076d4bf.partial: renamed to: sub dir/hello world 2026/02/04 04:03:19 INFO : sub dir/hello world: Copied (new) 2026/02/04 04:03:19 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 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: TestCopyRedownload Messages: directories --- FAIL: TestCopyRedownload (24.75s) === RUN TestSyncBasedOnCheckSum run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:03:34 DEBUG : check sum: Need to transfer - File not found at Destination 2026/02/04 04:03:34 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:03:34 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:03:38 DEBUG : check sum: size = 1 OK 2026/02/04 04:03:38 INFO : check sum: Copied (new) 2026/02/04 04:03:38 DEBUG : Waiting for deletions to finish 2026/02/04 04:03:38 INFO : sub dir: Removing directory 2026/02/04 04:03:39 DEBUG : drime root 'rclone-test-hohafiz2xeta': deleted 1 directories 2026/02/04 04:03:39 DEBUG : check sum: size = 1 OK 2026/02/04 04:03:39 NOTICE: drime root 'rclone-test-hohafiz2xeta': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/02/04 04:03:39 DEBUG : check sum: Size of src and dst objects identical 2026/02/04 04:03:39 DEBUG : check sum: Unchanged skipping 2026/02/04 04:03:39 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:03:39 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:03:39 DEBUG : Waiting for deletions to finish 2026/02/04 04:03:39 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go: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 (15.52s) === RUN TestSyncSizeOnly run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:03:49 DEBUG : sizeonly: Need to transfer - File not found at Destination 2026/02/04 04:03:49 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:03:49 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:03:55 DEBUG : sizeonly: size = 6 OK 2026/02/04 04:03:55 INFO : sizeonly: Copied (new) 2026/02/04 04:03:55 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:827 Error: Should be true Test: TestSyncSizeOnly Messages: listing wrong, want sizeonly (6) got fstest.go:203: Not found "sizeonly" 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:827 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSizeOnly Messages: 1 objects not found 2026/02/04 04:04:03 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:04:03 DEBUG : sizeonly: size = 6 OK 2026/02/04 04:04:03 DEBUG : sizeonly: Sizes identical 2026/02/04 04:04:03 DEBUG : sizeonly: Unchanged skipping 2026/02/04 04:04:03 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:04:03 DEBUG : Waiting for deletions to finish 2026/02/04 04:04:03 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: TestSyncSizeOnly Messages: listing wrong, want got sizeonly (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: TestSyncSizeOnly Messages: Unexpected file "sizeonly" --- FAIL: TestSyncSizeOnly (23.93s) === RUN TestSyncIgnoreSize run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:04:13 DEBUG : ignore-size: Need to transfer - File not found at Destination 2026/02/04 04:04:13 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:04:13 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:04:17 INFO : ignore-size: Copied (new) 2026/02/04 04:04:17 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:865 Error: Should be true Test: TestSyncIgnoreSize Messages: listing wrong, want ignore-size (8) got fstest.go:203: Not found "ignore-size" 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:865 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreSize Messages: 1 objects not found 2026/02/04 04:04:26 DEBUG : ignore-size: Sizes identical 2026/02/04 04:04:26 DEBUG : ignore-size: Unchanged skipping 2026/02/04 04:04:26 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:04:26 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:04:26 DEBUG : Waiting for deletions to finish 2026/02/04 04:04:26 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: TestSyncIgnoreSize Messages: listing wrong, want got ignore-size (8) 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: TestSyncIgnoreSize Messages: Unexpected file "ignore-size" --- FAIL: TestSyncIgnoreSize (22.87s) === RUN TestSyncIgnoreTimes run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:04:40 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:04:40 DEBUG : existing: size = 6 OK 2026/02/04 04:04:40 DEBUG : existing: Sizes identical 2026/02/04 04:04:40 DEBUG : existing: Unchanged skipping 2026/02/04 04:04:40 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:04:40 DEBUG : Waiting for deletions to finish 2026/02/04 04:04:40 INFO : There was nothing to transfer 2026/02/04 04:04:40 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:04:40 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2026/02/04 04:04:40 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:04:53 DEBUG : existing: Removing old object on successful upload 2026/02/04 04:04:53 DEBUG : existing: size = 6 OK 2026/02/04 04:04:53 INFO : existing: Copied (replaced existing) 2026/02/04 04:04:53 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: 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 (26.97s) === RUN TestSyncIgnoreExisting run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:05:03 DEBUG : existing: Destination exists, skipping 2026/02/04 04:05:03 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:05:03 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:05:03 DEBUG : Waiting for deletions to finish 2026/02/04 04:05:03 INFO : There was nothing to transfer 2026/02/04 04:05:03 DEBUG : existing: Destination exists, skipping 2026/02/04 04:05:03 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:05:03 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:05:03 DEBUG : Waiting for deletions to finish 2026/02/04 04:05:03 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.71s) === RUN TestSyncIgnoreErrors run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:05:09 INFO : d: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/04 04:05:22 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/04 04:05:22 DEBUG : c/non empty space: size = 5 OK 2026/02/04 04:05:22 DEBUG : c/non empty space: Sizes identical 2026/02/04 04:05:22 DEBUG : c/non empty space: Unchanged skipping 2026/02/04 04:05:22 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:05:22 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:05:25 DEBUG : a/potato2: size = 60 OK 2026/02/04 04:05:25 INFO : a/potato2: Copied (new) 2026/02/04 04:05:25 DEBUG : Waiting for deletions to finish 2026/02/04 04:05:26 INFO : b/potato: Deleted 2026/02/04 04:05:26 INFO : d: Removing directory 2026/02/04 04:05:26 INFO : b: Removing directory 2026/02/04 04:05:27 DEBUG : drime root 'rclone-test-hohafiz2xeta': deleted 2 directories run.go:130: removing dir "c" failed - try 1/3: directory not empty run.go:130: removing dir "c" failed - try 2/3: directory not empty run.go:130: removing dir "c" failed - try 3/3: directory not empty run.go:133: removing dir "c" failed: directory not empty run.go:130: removing dir "a" failed - try 1/3: directory not empty run.go:130: removing dir "a" failed - try 2/3: directory not empty run.go:130: removing dir "a" failed - try 3/3: directory not empty run.go:133: removing dir "a" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"a", "c"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=1) "a", + (string) (len=1) "c" } Test: TestSyncIgnoreErrors Messages: directories --- FAIL: TestSyncIgnoreErrors (41.14s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:05:50 DEBUG : empty space: size = 1 OK 2026/02/04 04:05:50 DEBUG : empty space: Sizes identical 2026/02/04 04:05:50 DEBUG : empty space: Unchanged skipping 2026/02/04 04:05:50 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:05:50 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:05:50 DEBUG : Waiting for deletions to finish 2026/02/04 04:05:50 NOTICE: c: Skipped remove directory as --dry-run is set 2026/02/04 04:05:50 NOTICE: a: Skipped remove directory as --dry-run is set 2026/02/04 04:05:50 DEBUG : drime root 'rclone-test-hohafiz2xeta': deleted 2 directories 2026/02/04 04:05:50 INFO : There was nothing to transfer 2026/02/04 04:05:51 DEBUG : empty space: size = 1 OK 2026/02/04 04:05:51 DEBUG : empty space: Sizes identical 2026/02/04 04:05:51 DEBUG : empty space: Unchanged skipping 2026/02/04 04:05:51 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:05:51 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:05:51 DEBUG : Waiting for deletions to finish 2026/02/04 04:05:51 INFO : c: Removing directory 2026/02/04 04:05:52 INFO : a: Removing directory 2026/02/04 04:05:53 DEBUG : drime root 'rclone-test-hohafiz2xeta': deleted 2 directories 2026/02/04 04:05:53 INFO : There was nothing to transfer 2026/02/04 04:05:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:05:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/04 04:05:55 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:05:55 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/04 04:05:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:05:55 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/04 04:05:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:05:56 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/04 04:05:56 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:05:56 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/04 04:05:56 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:05:56 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/04 04:05:56 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:05:56 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/04 04:05:57 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:05:57 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/04 04:05:58 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:05:58 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/04 04:06:01 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/04 04:06:01 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/04 04:06:06 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/04 04:06:16 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:06:16 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/04 04:06:21 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:06:21 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 04:06:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:06:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:07:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:07:32 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:07:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:08:12 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:08:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:08:52 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/04 04:09:12 DEBUG : pacer: Reducing sleep to 10s 2026/02/04 04:09:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:09:32 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 04:09:42 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:10:02 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:10:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:10:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:11:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:11:22 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:11:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:12:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:12:22 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/04 04:12:42 DEBUG : pacer: Reducing sleep to 10s 2026/02/04 04:13:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:13:02 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 04:13:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:13:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:13:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:14:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:14:32 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:14:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:15:12 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:15:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:15:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "a" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/04 04:16:12 DEBUG : pacer: Reducing sleep to 10s 2026/02/04 04:16:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:16:32 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 04:16:42 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:17:02 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:17:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:17:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:18:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:18:22 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:18:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:19:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:19:22 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "a" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/04 04:19:42 DEBUG : pacer: Reducing sleep to 10s 2026/02/04 04:20:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:20:02 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 04:20:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:20:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:20:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:21:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:21:32 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:21:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:22:12 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:22:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:22:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "a" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "a" failed: failed to delete item: Error "Server Error" 2026/02/04 04:23:12 DEBUG : pacer: Reducing sleep to 10s --- PASS: TestSyncAfterChangingModtimeOnly (1046.26s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:1046: Can't check this if no hashes supported 2026/02/04 04:23:32 DEBUG : pacer: Reducing sleep to 5s 2026/02/04 04:23:42 DEBUG : pacer: Reducing sleep to 2.5s --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (30.02s) === RUN TestSyncDoesntUpdateModtime run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:1072: Can't run this test on fs which doesn't support mod time 2026/02/04 04:23:47 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/04 04:23:49 DEBUG : pacer: Reducing sleep to 625ms --- SKIP: TestSyncDoesntUpdateModtime (7.54s) === RUN TestSyncAfterAddingAFile run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:23:51 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/04 04:23:57 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/04 04:23:57 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/04 04:23:57 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/04 04:23:57 DEBUG : empty space: size = 1 OK 2026/02/04 04:23:57 DEBUG : empty space: Sizes identical 2026/02/04 04:23:57 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/04 04:23:57 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:23:57 DEBUG : empty space: Unchanged skipping 2026/02/04 04:23:57 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:23:57 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/04 04:24:03 DEBUG : pacer: Reducing sleep to 10ms 2026/02/04 04:24:03 DEBUG : potato: size = 60 OK 2026/02/04 04:24:03 INFO : potato: Copied (new) 2026/02/04 04:24:03 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go: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: TestSyncAfterAddingAFile (38.25s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:24:35 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:24:35 DEBUG : potato: size = 21 (Local file system at /tmp/rclone3346432228) 2026/02/04 04:24:35 DEBUG : potato: size = 60 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 04:24:35 DEBUG : potato: Sizes differ 2026/02/04 04:24:35 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:24:39 DEBUG : potato: Removing old object on successful upload 2026/02/04 04:24:39 DEBUG : potato: size = 21 OK 2026/02/04 04:24:39 INFO : potato: Copied (replaced existing) 2026/02/04 04:24:39 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:1126 Error: Should be true Test: TestSyncAfterChangingFilesSizeOnly Messages: listing wrong, want potato (21) got potato (60) 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:1126 Error: Not equal: expected: 21 actual : 60 Test: TestSyncAfterChangingFilesSizeOnly Messages: potato: size incorrect file=21 vs obj=60 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: TestSyncAfterChangingFilesSizeOnly Messages: listing wrong, want got 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: TestSyncAfterChangingFilesSizeOnly Messages: Unexpected file "potato" --- FAIL: TestSyncAfterChangingFilesSizeOnly (29.63s) === RUN TestSyncAfterChangingContentsOnly run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:1136: ModTimeNotSupported so forcing file to be a different size 2026/02/04 04:24:59 DEBUG : potato: Removing old object on successful upload 2026/02/04 04:25:00 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:25:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/04 04:25:00 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:25:00 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/04 04:25:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:25:00 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/04 04:25:01 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:25:01 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/04 04:25:01 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:25:01 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/04 04:25:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:25:01 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/04 04:25:01 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:25:01 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/04 04:25:02 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:25:02 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/04 04:25:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:25:03 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/04 04:25:06 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/04 04:25:06 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:286: Retry Put of "potato" to drime root 'rclone-test-hohafiz2xeta': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error") 2026/02/04 04:25:11 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/04 04:25:25 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/04 04:25:25 DEBUG : potato: Removing old object on successful upload 2026/02/04 04:25:26 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/04 04:25:29 DEBUG : pacer: Reducing sleep to 640ms 2026/02/04 04:25:30 DEBUG : pacer: Reducing sleep to 320ms 2026/02/04 04:25:30 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:25:30 DEBUG : potato: size = 21 (Local file system at /tmp/rclone3346432228) 2026/02/04 04:25:30 DEBUG : potato: size = 36 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 04:25:30 DEBUG : potato: Sizes differ 2026/02/04 04:25:30 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:25:37 DEBUG : pacer: Reducing sleep to 160ms 2026/02/04 04:25:37 DEBUG : potato: Removing old object on successful upload 2026/02/04 04:25:37 DEBUG : pacer: Reducing sleep to 80ms 2026/02/04 04:25:37 DEBUG : potato: size = 21 OK 2026/02/04 04:25:37 INFO : potato: Copied (replaced existing) 2026/02/04 04:25:37 DEBUG : Waiting for deletions to finish 2026/02/04 04:25:37 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/04 04:25:39 DEBUG : pacer: Reducing sleep to 20ms 2026/02/04 04:25:39 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: TestSyncAfterChangingContentsOnly (62.90s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:26:06 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 04:26:06 DEBUG : empty space: size = 1 OK 2026/02/04 04:26:06 DEBUG : empty space: Sizes identical 2026/02/04 04:26:06 DEBUG : empty space: Unchanged skipping 2026/02/04 04:26:06 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:26:06 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:26:06 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2026/02/04 04:26:06 DEBUG : Waiting for deletions to finish 2026/02/04 04:26:06 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/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 (15.90s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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/04 04:26:34 DEBUG : empty space: size = 1 OK 2026/02/04 04:26:34 DEBUG : empty space: Sizes identical 2026/02/04 04:26:34 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 04:26:34 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:26:34 DEBUG : empty space: Unchanged skipping 2026/02/04 04:26:34 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:26:38 DEBUG : potato2: size = 60 OK 2026/02/04 04:26:38 INFO : potato2: Copied (new) 2026/02/04 04:26:38 DEBUG : Waiting for deletions to finish 2026/02/04 04:26:38 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/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 (31.84s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:26:55 INFO : d: Making directory 2026/02/04 04:26:56 INFO : d/e: Making directory 2026/02/04 04:26:57 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/04 04:26:58 DEBUG : c/non empty space: size = 5 OK 2026/02/04 04:26:58 DEBUG : c/non empty space: Sizes identical 2026/02/04 04:26:58 DEBUG : c/non empty space: Unchanged skipping 2026/02/04 04:26:59 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:26:59 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:27:02 DEBUG : a/potato2: size = 60 OK 2026/02/04 04:27:02 INFO : a/potato2: Copied (new) 2026/02/04 04:27:02 DEBUG : Waiting for deletions to finish 2026/02/04 04:27:02 INFO : b/potato: Deleted 2026/02/04 04:27:02 INFO : d/e: Removing directory 2026/02/04 04:27:02 INFO : d: Removing directory 2026/02/04 04:27:03 DEBUG : d: Failed to Rmdir: directory not empty 2026/02/04 04:27:03 INFO : b: Removing directory 2026/02/04 04:27:03 DEBUG : b: Failed to Rmdir: directory not empty 2026/02/04 04:27:03 DEBUG : drime root 'rclone-test-hohafiz2xeta': failed to delete 2 directories 2026/02/04 04:27:03 DEBUG : drime root 'rclone-test-hohafiz2xeta': 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 (40.53s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:27:39 INFO : d: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/04 04:27:52 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/04 04:27:53 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:27:53 DEBUG : c/non empty space: size = 5 OK 2026/02/04 04:27:53 DEBUG : c/non empty space: Sizes identical 2026/02/04 04:27:53 DEBUG : c/non empty space: Unchanged skipping 2026/02/04 04:27:53 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:27:57 DEBUG : a/potato2: size = 60 OK 2026/02/04 04:27:57 INFO : a/potato2: Copied (new) 2026/02/04 04:27:57 ERROR : drime root 'rclone-test-hohafiz2xeta': not deleting files as there were IO errors 2026/02/04 04:27:57 ERROR : drime root 'rclone-test-hohafiz2xeta': not deleting directories as there were IO errors fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go: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:1317 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: listing wrong, want a/potato2 (60), b/potato (21), c/non empty space (5) got b/potato (21), c/non empty space (5) fstest.go:203: Not found "a/potato2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1317 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors 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:1317 Error: Not equal: expected: []string{"a", "b", "c", "d"} actual : []string{"b", "c", "d"} Diff: --- Expected +++ Actual @@ -1,3 +1,2 @@ -([]string) (len=4) { - (string) (len=1) "a", +([]string) (len=3) { (string) (len=1) "b", Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: directories run.go:130: removing dir "c" failed - try 1/3: directory not empty run.go:130: removing dir "c" failed - try 2/3: directory not empty run.go:130: removing dir "c" failed - try 3/3: directory not empty run.go:133: removing dir "c" failed: directory not empty run.go:130: removing dir "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 run.go:130: removing dir "a" failed - try 1/3: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 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 (60.31s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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/04 04:28:48 DEBUG : Waiting for deletions to finish 2026/02/04 04:28:50 DEBUG : empty space: size = 1 OK 2026/02/04 04:28:50 DEBUG : empty space: Sizes identical 2026/02/04 04:28:50 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 04:28:50 DEBUG : empty space: Unchanged skipping 2026/02/04 04:28:52 INFO : potato: Deleted 2026/02/04 04:28:52 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:28:52 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:28:55 DEBUG : potato2: size = 60 OK 2026/02/04 04:28:55 INFO : potato2: Copied (new) 2026/02/04 04:28:55 INFO : c: Removing directory 2026/02/04 04:28:55 INFO : b: Removing directory 2026/02/04 04:28:56 DEBUG : drime root 'rclone-test-hohafiz2xeta': 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/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:1348 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncDeleteDuring Messages: listing wrong, want empty space (1), potato2 (60) got empty space (1) 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:1348 Error: Not equal: expected: 0 actual : 1 Test: TestSyncDeleteDuring 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: 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 (49.23s) === RUN TestSyncDeleteBefore run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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 fstest.go:203: Not found "empty space" fstest.go:203: Not found "potato" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 Error: Not equal: expected: 0 actual : 2 Test: TestSyncDeleteBefore Messages: 2 objects not found 2026/02/04 04:29:32 DEBUG : Waiting for deletions to finish 2026/02/04 04:29:33 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:29:33 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:29:33 INFO : potato: Deleted 2026/02/04 04:29:33 DEBUG : empty space: size = 1 OK 2026/02/04 04:29:33 DEBUG : empty space: Sizes identical 2026/02/04 04:29:33 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 04:29:33 DEBUG : empty space: Unchanged skipping 2026/02/04 04:29:33 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:29:33 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:29:39 DEBUG : potato2: size = 60 OK 2026/02/04 04:29:39 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: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 --- FAIL: TestSyncDeleteBefore (44.48s) === RUN TestCopyDeleteBefore run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:30:09 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 04:30:09 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:30:09 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:30:12 DEBUG : potato2: size = 19 OK 2026/02/04 04:30:12 INFO : potato2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1379 Error: Should be true Test: TestCopyDeleteBefore Messages: listing wrong, want potato (21), potato2 (19) got potato (21) fstest.go:203: Not found "potato2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1379 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyDeleteBefore Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyDeleteBefore Messages: listing wrong, want got potato (21), potato2 (19) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyDeleteBefore Messages: Unexpected file "potato" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyDeleteBefore Messages: Unexpected file "potato2" --- FAIL: TestCopyDeleteBefore (27.79s) === RUN TestSyncWithExclude run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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/04 04:30:51 DEBUG : enormous: Excluded (Size Filter) 2026/02/04 04:30:51 DEBUG : enormous: Excluded 2026/02/04 04:30:51 DEBUG : potato2: Excluded (Size Filter) 2026/02/04 04:30:51 DEBUG : potato2: Excluded 2026/02/04 04:30:51 DEBUG : potato2: Excluded (Size Filter) 2026/02/04 04:30:51 DEBUG : potato2: Excluded 2026/02/04 04:30:51 DEBUG : empty space: size = 1 OK 2026/02/04 04:30:51 DEBUG : empty space: Sizes identical 2026/02/04 04:30:51 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:30:51 DEBUG : empty space: Unchanged skipping 2026/02/04 04:30:51 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:30:51 DEBUG : Waiting for deletions to finish 2026/02/04 04:30:51 INFO : There was nothing to transfer 2026/02/04 04:30:51 DEBUG : enormous: Excluded (Size Filter) 2026/02/04 04:30:51 DEBUG : enormous: Excluded 2026/02/04 04:30:51 DEBUG : potato2: Excluded (Size Filter) 2026/02/04 04:30:51 DEBUG : potato2: Excluded 2026/02/04 04:30:51 DEBUG : potato2: Excluded (Size Filter) 2026/02/04 04:30:51 DEBUG : potato2: Excluded 2026/02/04 04:30:51 DEBUG : empty space: size = 1 OK 2026/02/04 04:30:51 DEBUG : empty space: Sizes identical 2026/02/04 04:30:51 DEBUG : empty space: Unchanged skipping 2026/02/04 04:30:51 DEBUG : Local file system at /tmp/rclone3346432228: Waiting for checks to finish 2026/02/04 04:30:51 DEBUG : Local file system at /tmp/rclone3346432228: Waiting for transfers to finish 2026/02/04 04:30:51 DEBUG : Waiting for deletions to finish 2026/02/04 04:30:51 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestSyncWithExclude (30.06s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", 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/04 04:31:23 DEBUG : enormous: Excluded (Size Filter) 2026/02/04 04:31:23 DEBUG : enormous: Excluded 2026/02/04 04:31:23 DEBUG : potato2: Excluded (Size Filter) 2026/02/04 04:31:23 DEBUG : potato2: Excluded 2026/02/04 04:31:24 DEBUG : empty space: size = 1 OK 2026/02/04 04:31:24 DEBUG : empty space: Sizes identical 2026/02/04 04:31:24 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:31:24 DEBUG : empty space: Unchanged skipping 2026/02/04 04:31:24 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:31:24 DEBUG : Waiting for deletions to finish 2026/02/04 04:31:24 INFO : enormous: Deleted 2026/02/04 04:31:24 INFO : potato2: Deleted 2026/02/04 04:31:24 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/04 04:31:33 DEBUG : Local file system at /tmp/rclone3346432228: Waiting for checks to finish 2026/02/04 04:31:33 DEBUG : empty space: size = 1 OK 2026/02/04 04:31:33 DEBUG : empty space: Sizes identical 2026/02/04 04:31:33 DEBUG : empty space: Unchanged skipping 2026/02/04 04:31:33 DEBUG : Local file system at /tmp/rclone3346432228: Waiting for transfers to finish 2026/02/04 04:31:33 DEBUG : Waiting for deletions to finish 2026/02/04 04:31:33 INFO : enormous: Deleted 2026/02/04 04:31:33 INFO : potato2: Deleted 2026/02/04 04:31:33 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 (45.08s) === RUN TestSyncWithUpdateOlder run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:1454: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncWithUpdateOlder (0.66s) === 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-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:1569: Can track renames: false 2026/02/04 04:31:46 ERROR : drime root 'rclone-test-hohafiz2xeta': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/04 04:31:46 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/04 04:31:46 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/04 04:31:46 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:31:46 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:31:49 DEBUG : potato: size = 14 OK 2026/02/04 04:31:49 INFO : potato: Copied (new) 2026/02/04 04:31:50 DEBUG : yam: size = 11 OK 2026/02/04 04:31:50 INFO : yam: Copied (new) 2026/02/04 04:31:50 DEBUG : Waiting for deletions to finish 2026/02/04 04:31:50 ERROR : drime root 'rclone-test-hohafiz2xeta': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/04 04:31:51 DEBUG : potato: size = 14 OK 2026/02/04 04:31:51 DEBUG : potato: Sizes identical 2026/02/04 04:31:51 DEBUG : yaml: Need to transfer - File not found at Destination 2026/02/04 04:31:51 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:31:51 DEBUG : potato: Unchanged skipping 2026/02/04 04:31:51 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:31:53 DEBUG : yaml: size = 11 OK 2026/02/04 04:31:53 INFO : yaml: Copied (new) 2026/02/04 04:31:53 DEBUG : Waiting for deletions to finish 2026/02/04 04:31:54 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 (25.81s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:1641: Can track renames: false 2026/02/04 04:32:12 ERROR : drime root 'rclone-test-hohafiz2xeta': Ignoring --track-renames as either the source or destination do not support modtime 2026/02/04 04:32:12 DEBUG : potato: size = 14 OK 2026/02/04 04:32:12 DEBUG : potato: Sizes identical 2026/02/04 04:32:12 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/04 04:32:12 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:32:12 DEBUG : potato: Unchanged skipping 2026/02/04 04:32:12 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:32:17 DEBUG : yam: size = 11 OK 2026/02/04 04:32:17 INFO : yam: Copied (new) 2026/02/04 04:32:17 DEBUG : Waiting for deletions to finish 2026/02/04 04:32:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:32:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/04 04:32:18 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:32:18 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/04 04:32:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:32:18 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/04 04:32:18 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:32:18 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/04 04:32:19 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:32:19 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/04 04:32:19 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:32:19 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/04 04:32:19 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:32:19 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/04 04:32:20 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:32:20 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/04 04:32:21 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:32:21 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/04 04:32:24 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/04 04:32:24 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/04 04:32:24 ERROR : yaml: Couldn't delete: failed to delete item: Error "Server Error" 2026/02/04 04:32:24 ERROR : drime root 'rclone-test-hohafiz2xeta': 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/04 04:32:29 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/04 04:32:39 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/04 04:32:44 DEBUG : pacer: Reducing sleep to 1.28s --- FAIL: TestSyncWithTrackRenamesStrategyModtime (32.65s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" sync_test.go:1680: Can track renames: false 2026/02/04 04:32:47 DEBUG : pacer: Reducing sleep to 640ms 2026/02/04 04:32:47 INFO : drime root 'rclone-test-hohafiz2xeta': Making map for --track-renames 2026/02/04 04:32:47 INFO : drime root 'rclone-test-hohafiz2xeta': Finished making map for --track-renames 2026/02/04 04:32:47 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:32:47 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/02/04 04:32:47 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2026/02/04 04:32:47 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for renames to finish 2026/02/04 04:32:47 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:32:48 DEBUG : pacer: Reducing sleep to 320ms 2026/02/04 04:32:49 DEBUG : pacer: Reducing sleep to 160ms 2026/02/04 04:32:49 DEBUG : pacer: Reducing sleep to 80ms 2026/02/04 04:32:50 DEBUG : pacer: Reducing sleep to 40ms 2026/02/04 04:32:52 DEBUG : pacer: Reducing sleep to 20ms 2026/02/04 04:32:52 DEBUG : sub/yam: size = 11 OK 2026/02/04 04:32:52 INFO : sub/yam: Copied (new) 2026/02/04 04:32:54 DEBUG : pacer: Reducing sleep to 10ms 2026/02/04 04:32:54 DEBUG : potato: size = 14 OK 2026/02/04 04:32:54 INFO : potato: Copied (new) 2026/02/04 04:32:54 DEBUG : Waiting for deletions to finish 2026/02/04 04:32:55 DEBUG : potato: size = 14 OK 2026/02/04 04:32:55 DEBUG : potato: Sizes identical 2026/02/04 04:32:55 DEBUG : potato: Unchanged skipping 2026/02/04 04:32:56 INFO : drime root 'rclone-test-hohafiz2xeta': Making map for --track-renames 2026/02/04 04:32:56 INFO : drime root 'rclone-test-hohafiz2xeta': Finished making map for --track-renames 2026/02/04 04:32:56 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:32:56 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for renames to finish 2026/02/04 04:32:56 ERROR : sub/yam: Couldn't move: object not found 2026/02/04 04:32:56 DEBUG : yam: Failed to rename to "sub/yam": object not found 2026/02/04 04:32:56 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/02/04 04:32:56 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:33:00 DEBUG : yam: size = 11 OK 2026/02/04 04:33:00 INFO : yam: Copied (new) 2026/02/04 04:33:00 ERROR : drime root 'rclone-test-hohafiz2xeta': not deleting files as there were IO errors 2026/02/04 04:33:00 ERROR : drime root 'rclone-test-hohafiz2xeta': 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 (29.55s) === RUN TestServerSideMoveLocal run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:33:14 DEBUG : Creating backend with remote "/tmp/rclone3346432228/dir1" 2026/02/04 04:33:14 DEBUG : Config file has changed externally - reloading 2026/02/04 04:33:14 DEBUG : Creating backend with remote "/tmp/rclone3346432228/dir2" 2026/02/04 04:33:14 DEBUG : Local file system at /tmp/rclone3346432228/dir2: Using server-side directory move 2026/02/04 04:33:14 INFO : Local file system at /tmp/rclone3346432228/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/04 04:33:14 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/04 04:33:14 INFO : file1.txt: Moved (server-side) 2026/02/04 04:33:14 DEBUG : Local file system at /tmp/rclone3346432228/dir2: Waiting for checks to finish 2026/02/04 04:33:14 DEBUG : Local file system at /tmp/rclone3346432228/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (1.72s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:33:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 04:33:16 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/04 04:33:16 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:33:16 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:33:20 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/04 04:33:20 INFO : nested/sub dir/file: Copied (new) 2026/02/04 04:33:20 INFO : nested/sub dir/file: Deleted 2026/02/04 04:33:20 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 04:33:20 INFO : sub dir/hello world: Copied (new) 2026/02/04 04:33:20 INFO : sub dir/hello world: Deleted 2026/02/04 04:33:20 ERROR : Local file system at /tmp/rclone3346432228: 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 (5.04s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:33:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/04 04:33:21 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/04 04:33:21 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:33:21 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:33:23 DEBUG : nested/sub dir/file: Removing old object on successful upload 2026/02/04 04:33:23 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/04 04:33:24 DEBUG : sub dir/hello world: size = 11 OK 2026/02/04 04:33:24 INFO : sub dir/hello world: Copied (new) 2026/02/04 04:33:24 INFO : sub dir/hello world: Deleted 2026/02/04 04:33:24 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/04 04:33:24 INFO : nested/sub dir/file: Copied (new) 2026/02/04 04:33:24 INFO : nested/sub dir/file: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go: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 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: TestMoveWithoutDeleteEmptySrcDirs Messages: directories --- FAIL: TestMoveWithoutDeleteEmptySrcDirs (34.76s) === RUN TestMoveWithIgnoreExisting run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:33:56 DEBUG : existing-b: Need to transfer - File not found at Destination 2026/02/04 04:33:56 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/04 04:33:56 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:33:56 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:33:58 DEBUG : existing: size = 6 OK 2026/02/04 04:33:58 INFO : existing: Copied (new) 2026/02/04 04:33:58 INFO : existing: Deleted 2026/02/04 04:34:00 DEBUG : existing-b: size = 6 OK 2026/02/04 04:34:00 INFO : existing-b: Copied (new) 2026/02/04 04:34:00 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 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/04 04:34:10 DEBUG : existing: Destination exists, skipping 2026/02/04 04:34:10 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2026/02/04 04:34:11 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for checks to finish 2026/02/04 04:34:11 DEBUG : drime root 'rclone-test-hohafiz2xeta': Waiting for transfers to finish 2026/02/04 04:34:11 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1898 Error: Not equal: expected: []string{} actual : []string{"nested", "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 "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"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=6) "nested" } Test: TestMoveWithIgnoreExisting Messages: directories --- FAIL: TestMoveWithIgnoreExisting (41.53s) === RUN TestServerSideMove run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 04:34:37 DEBUG : Creating backend with remote "TestDrime:rclone-test-qafiyed8muhu" 2026/02/04 04:34:37 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-hohafiz2xeta' -> drime root 'rclone-test-qafiyed8muhu' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1741 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1912 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato3 (60) got fstest.go:203: Not found "potato3" fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1741 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go: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/04 04:35:18 DEBUG : drime root 'rclone-test-qafiyed8muhu': Using server-side directory move 2026/02/04 04:35:18 INFO : drime root 'rclone-test-qafiyed8muhu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/04 04:35:19 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 04:35:19 DEBUG : potato3: size = 68 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 04:35:19 DEBUG : potato3: size = 60 (drime root 'rclone-test-qafiyed8muhu') 2026/02/04 04:35:19 DEBUG : potato3: Sizes differ 2026/02/04 04:35:19 DEBUG : empty space: size = 1 OK 2026/02/04 04:35:19 DEBUG : empty space: Sizes identical 2026/02/04 04:35:19 DEBUG : empty space: Unchanged skipping 2026/02/04 04:35:19 DEBUG : drime root 'rclone-test-qafiyed8muhu': Waiting for checks to finish 2026/02/04 04:35:19 INFO : empty space: Deleted 2026/02/04 04:35:19 DEBUG : drime root 'rclone-test-qafiyed8muhu': Waiting for transfers to finish 2026/02/04 04:35:19 INFO : potato3: Deleted 2026/02/04 04:35:19 ERROR : potato2: Couldn't move: object not found 2026/02/04 04:35:20 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/04 04:35:20 DEBUG : drime root 'rclone-test-qafiyed8muhu': Purge remote 2026/02/04 04:35:20 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:35:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/04 04:35:21 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:35:21 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/04 04:35:21 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:35:21 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/04 04:35:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:35:22 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/04 04:35:22 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:35:22 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/04 04:35:22 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:35:22 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/04 04:35:22 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:35:22 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/04 04:35:23 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:35:23 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/04 04:35:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:35:24 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/04 04:35:27 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/04 04:35:27 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/04 04:35:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:35:32 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 04:35:42 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:36:02 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:36:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:36:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:37:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:37:22 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:37:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:38:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:38:22 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/04 04:38:43 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:39:03 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:39:23 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:39:42 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:40:03 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:40:23 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:40:43 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:41:03 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:41:23 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:41:43 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/04 04:42:03 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:42:23 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:42:43 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:43:03 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:43:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:43:43 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:44:03 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:44:23 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:44:43 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:45:03 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/04 04:45:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:45:43 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:46:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:46:23 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:46:43 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:47:03 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:47:23 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:47:43 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:48:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:48:23 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/04 04:48:43 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:49:03 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:49:23 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:49:43 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:50:03 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:50:23 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:50:43 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:51:03 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:51:23 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:51:43 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/04 04:52:03 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:52:23 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:52:43 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:53:03 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:53:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:53:43 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:54:04 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:54:23 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:54:43 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:55:03 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/04 04:55:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:55:43 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:56:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:56:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 04:56:43 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 04:57:03 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 04:57:23 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 04:57:44 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 04:58:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 04:58: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/04 04:58:43 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 04:59:04 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 04:59:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 04:59:43 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:00:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:00:23 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:00:43 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:01:03 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:01:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:01: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/04 05:02:03 DEBUG : pacer: Reducing sleep to 10s 2026/02/04 05:02:24 DEBUG : pacer: Reducing sleep to 5s 2026/02/04 05:02:34 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/04 05:02:39 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestServerSideMove (1681.64s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 05:02:39 DEBUG : Creating backend with remote "TestDrime:rclone-test-jeqifad5zimi" 2026/02/04 05:02:39 DEBUG : Config file has changed externally - reloading 2026/02/04 05:02:41 DEBUG : pacer: Reducing sleep to 625ms 2026/02/04 05:02:45 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/04 05:02:46 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/04 05:02:53 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/04 05:02:54 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/04 05:02:59 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/04 05:02:59 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/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:1734 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1925 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60) 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: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:1734 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1925 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveWithFilter Messages: 1 objects not found sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-hohafiz2xeta' -> drime root 'rclone-test-jeqifad5zimi' 2026/02/04 05:03:20 DEBUG : empty space: Excluded (Size Filter) 2026/02/04 05:03:20 DEBUG : empty space: Excluded 2026/02/04 05:03:20 DEBUG : empty space: Excluded (Size Filter) 2026/02/04 05:03:20 DEBUG : empty space: Excluded 2026/02/04 05:03:20 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 05:03:20 DEBUG : potato3: size = 68 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 05:03:20 DEBUG : potato3: size = 60 (drime root 'rclone-test-jeqifad5zimi') 2026/02/04 05:03:20 DEBUG : potato3: Sizes differ 2026/02/04 05:03:20 DEBUG : drime root 'rclone-test-jeqifad5zimi': Waiting for checks to finish 2026/02/04 05:03:20 DEBUG : drime root 'rclone-test-jeqifad5zimi': Waiting for transfers to finish 2026/02/04 05:03:20 INFO : potato3: Deleted 2026/02/04 05:03:21 ERROR : potato2: Couldn't move: object not found 2026/02/04 05:03: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:1925 Error: Received unexpected error: object not found Test: TestServerSideMoveWithFilter 2026/02/04 05:03:21 DEBUG : drime root 'rclone-test-jeqifad5zimi': Purge remote 2026/02/04 05:03:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:03:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/04 05:03:23 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:03:23 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/04 05:03:23 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:03:23 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/04 05:03:23 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:03:23 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/04 05:03:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:03:24 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/04 05:03:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:03:24 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/04 05:03:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:03:24 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/04 05:03:25 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:03:25 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/04 05:03:26 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:03:26 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/04 05:03:29 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/04 05:03:29 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/04 05:03:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 05:03:44 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:04:04 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:04:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:04:44 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:05:05 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:05:25 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:05:44 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:06:05 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:06: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/04 05:06:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:07:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:07:25 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:07:45 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:08:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:08:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:08:44 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:09:04 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:09:25 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:09: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/04 05:10:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:10:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:10:45 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:11:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:11:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:11:45 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:12:05 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:12:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:12:45 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:13:05 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/04 05:13:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:13:45 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:14:05 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:14:25 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:14:45 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:15:05 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:15:25 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:15:45 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:16:05 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:16:25 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/04 05:16:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:17:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:17:25 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:17:45 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:18:05 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:18:25 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:18:45 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:19:05 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:19:25 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:19:45 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/04 05:20:05 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestServerSideMoveWithFilter (1046.66s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "drime root 'rclone-test-hohafiz2xeta'", Local "Local file system at /tmp/rclone3346432228", Modify Window "876000h0m0s" 2026/02/04 05:20:05 DEBUG : Creating backend with remote "TestDrime:rclone-test-xovucif8waxu" 2026/02/04 05:20:05 DEBUG : Config file has changed externally - reloading 2026/02/04 05:20:25 DEBUG : pacer: Reducing sleep to 5s 2026/02/04 05:20:38 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/04 05:20:40 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/04 05:20:50 DEBUG : pacer: Reducing sleep to 625ms 2026/02/04 05:20:50 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/04 05:20:54 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/04 05:20:54 INFO : tomatoDir: Making directory 2026/02/04 05:20:55 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/04 05:20:55 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/04 05:20:56 DEBUG : pacer: Reducing sleep to 19.53125ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/04 05:20:58 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 sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-hohafiz2xeta' -> drime root 'rclone-test-xovucif8waxu' 2026/02/04 05:21:29 DEBUG : drime root 'rclone-test-xovucif8waxu': Using server-side directory move 2026/02/04 05:21:29 INFO : drime root 'rclone-test-xovucif8waxu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/04 05:21:29 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/04 05:21:29 DEBUG : empty space: size = 1 OK 2026/02/04 05:21:29 DEBUG : empty space: Sizes identical 2026/02/04 05:21:29 DEBUG : empty space: Unchanged skipping 2026/02/04 05:21:29 DEBUG : potato3: size = 68 (drime root 'rclone-test-hohafiz2xeta') 2026/02/04 05:21:29 DEBUG : potato3: size = 60 (drime root 'rclone-test-xovucif8waxu') 2026/02/04 05:21:29 DEBUG : potato3: Sizes differ 2026/02/04 05:21:30 DEBUG : drime root 'rclone-test-xovucif8waxu': Waiting for checks to finish 2026/02/04 05:21:30 INFO : potato3: Deleted 2026/02/04 05:21:30 INFO : empty space: Deleted 2026/02/04 05:21:30 DEBUG : drime root 'rclone-test-xovucif8waxu': Waiting for transfers to finish 2026/02/04 05:21:30 ERROR : potato2: Couldn't move: object not found 2026/02/04 05:21:30 ERROR : potato3: Couldn't move: object not found 2026/02/04 05:21:30 ERROR : drime root 'rclone-test-hohafiz2xeta': 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/04 05:21:30 DEBUG : drime root 'rclone-test-xovucif8waxu': Purge remote 2026/02/04 05:21:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:21:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/04 05:21:32 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:21:32 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/04 05:21:33 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:21:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/04 05:21:33 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:21:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/04 05:21:34 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:21:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/04 05:21:34 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:21:34 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/04 05:21:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:21:35 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/04 05:21:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:21:35 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/04 05:21:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:21:36 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/04 05:21:39 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/04 05:21:39 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "empty space" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/04 05:21:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:21:44 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/04 05:21:55 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:22:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:22:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:22:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:23:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:23:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:23:55 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:24:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:24:35 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/04 05:24:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:25:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:25:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:25:55 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:26:15 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:26:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:26:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:27:15 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:27:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:27:55 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/04 05:28:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:28:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:28:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:29:15 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:29:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:29:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:30:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:30:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:30:55 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:31:15 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/04 05:31:35 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:31:55 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:32:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:32:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:32:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:33:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:33:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:33:55 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:34:15 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:34:35 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/04 05:34:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/04 05:35:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/04 05:35:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/04 05:35:55 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/04 05:36:15 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/04 05:36:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/04 05:36:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/04 05:37:15 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/04 05:37:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/04 05:37:55 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "potato2" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "potato2" failed: failed to delete item: Error "Server Error" 2026/02/04 05:38:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") panic: test timed out after 2h0m0s running tests: TestServerSideMoveDeleteEmptySourceDirs (18m14s) goroutine 11481 [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, 18 minutes]: testing.(*T).Run(0xc000408e00, {0x29a88e0?, 0xc000707ad8?}, 0x2ab0ce0) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc000408e00) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc000408e00, 0xc000707c18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc0006f6690, {0x44671e0, 0x6c, 0x6c}, {0x84a160?, 0x44a31f0?, 0x447f440?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0005e65a0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0005e65a0) /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 25 [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 26 [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 38 [IO wait]: internal/poll.runtime_pollWait(0x71844cb89ed8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0007ba280?, 0xc0004d9300?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0007ba280, {0xc0004d9300, 0x1300, 0x1300}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0007ba280, {0xc0004d9300?, 0x12fb?, 0xc0005705e0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00013a7f0, {0xc0004d9300?, 0xc0004d9300?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0004a7a28, {0xc0004d9300?, 0x71844c078b60?, 0x718495089108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000d7c048, {0xc0004d9300?, 0x12fb?, 0x1?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000698638, {0x2e29360, 0xc000d7c048}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000698388, {0x71844c06f6e0, 0xc0004a7a28}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000698388, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000698388, {0xc00067a000, 0x1000, 0xc000103a40?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 bufio.(*Reader).Read(0xc0009a8de0, {0xc00099aac0, 0x9, 0x43e8760?}) /usr/local/go/src/bufio/bufio.go:245 +0x197 io.ReadAtLeast({0x2e263c0, 0xc0009a8de0}, {0xc00099aac0, 0x9, 0x9}, 0x9) /usr/local/go/src/io/io.go:335 +0x91 io.ReadFull(...) /usr/local/go/src/io/io.go:354 net/http.http2readFrameHeader({0xc00099aac0, 0x9, 0xc000270b10?}, {0x2e263c0?, 0xc0009a8de0?}) /usr/local/go/src/net/http/h2_bundle.go:1805 +0x65 net/http.(*http2Framer).ReadFrame(0xc00099aa80) /usr/local/go/src/net/http/h2_bundle.go:2072 +0x7d net/http.(*http2clientConnReadLoop).run(0xc00076dfa8) /usr/local/go/src/net/http/h2_bundle.go:9933 +0xda net/http.(*http2ClientConn).readLoop(0xc000496700) /usr/local/go/src/net/http/h2_bundle.go:9812 +0x79 created by net/http.(*http2Transport).newClientConn in goroutine 37 /usr/local/go/src/net/http/h2_bundle.go:8334 +0xde5 goroutine 11305 [select, 17 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 11139 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11306 [select, 17 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 11139 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11139 [sync.WaitGroup.Wait, 17 minutes]: sync.runtime_SemacquireWaitGroup(0xc000ac17d0?) /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, 0xc000762000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc000590d80, 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, 0xc000762000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc000590d80) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:76 +0x2a5 github.com/rclone/rclone/fs/walk.listRwalk({0x2e502b8, 0x44a2c60}, {0x2e67310, 0xc000762000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc000590d20) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:170 +0x10d github.com/rclone/rclone/fs/walk.ListR({0x2e502b8, 0x44a2c60}, {0x2e67310, 0xc000762000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc000590d20) /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(0xc000444230) /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(0xc000d68540, 0xc000970380?) /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(0xc000d68540) /usr/local/go/src/testing/testing.go:1041 +0x4a github.com/stretchr/testify/require.NoError({0x2e3a190, 0xc000d68540}, {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}, 0xc000d68540, 0xc000444230, 0x0, 0x1) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747 +0x827 github.com/rclone/rclone/fs/sync.TestServerSideMoveDeleteEmptySourceDirs(0xc000d68540) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1932 +0x48 testing.tRunner(0xc000d68540, 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 11300 [select, 17 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 11139 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11302 [select, 17 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 11139 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11301 [select, 17 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 11139 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11307 [chan receive]: github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall(0xc0001161e0, 0x0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:162 +0x26 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0001161e0, 0xc00035c5c0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:215 +0xb2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0001161e0, 0xc00035c5c0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:235 +0x93 github.com/rclone/rclone/backend/drime.(*Fs).deleteObject(0xc000762000, {0x2e502b8, 0x44a2c60}, {0xc00058a4b7, 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(0xc000d68540, {0xc000d12090, 0x17}, 0xc00083dd28) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:126 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc0000c62c0?, 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?}, {0xc0000c62c0, 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 11139 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 4180 [chan receive, 96 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 4155 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 11303 [select, 17 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 11139 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11304 [select, 17 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 11139 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11465 [sleep]: time.Sleep(0x4a817c800) /usr/local/go/src/runtime/time.go:338 +0x165 github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0x0?) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:170 +0x1c created by github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall in goroutine 11307 /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:169 +0xf6 "./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Finished ERROR in 2h0m1.340498968s (try 1/5): exit status 2: Failed [TestCopy TestCopyMetadata TestCopyNoTraverse TestCopyNoTraverseDeadlock TestCopyCheckFirst TestSyncNoTraverse TestCopyWithDepth TestCopyWithFilesFrom TestCopyEmptyDirectories TestCopyNoEmptyDirectories TestMoveEmptyDirectories TestMoveNoEmptyDirectories TestSyncEmptyDirectories TestSyncNoEmptyDirectories TestServerSideCopy TestCopyOverSelf TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf TestCopyAfterDelete TestCopyRedownload TestSyncBasedOnCheckSum TestSyncSizeOnly TestSyncIgnoreSize TestSyncIgnoreTimes TestSyncIgnoreErrors TestSyncAfterChangingFilesSizeOnly TestSyncAfterRemovingAFileAndAddingAFileDryRun TestSyncAfterRemovingAFileAndAddingAFile TestSyncAfterRemovingAFileAndAddingAFileSubDir TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors TestSyncDeleteDuring TestSyncDeleteBefore TestCopyDeleteBefore TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestMoveWithoutDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMove TestServerSideMoveWithFilter]