"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Starting (try 1/5) 2026/02/05 01:09:58 DEBUG : Creating backend with remote "TestDrime:rclone-test-dohugah5vuru" 2026/02/05 01:09:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 01:09:59 DEBUG : Creating backend with remote "/tmp/rclone23648990" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.01s) === 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-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:10:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:10:00 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:10:00 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2026/02/05 01:10:00 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.57s) === RUN TestCopy run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:10:01 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/05 01:10:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:10:01 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:10:01 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:10:04 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:10:04 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/05 01:10:05 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/05 01:10:12 DEBUG : drime root 'rclone-test-dohugah5vuru': 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 (26.30s) === RUN TestCopyMetadata run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", 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 (10.36s) === RUN TestCopyMetadataNoEmptyDirs run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.67s) === RUN TestCopyMissingDirectory run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:10:38 DEBUG : Creating backend with remote "/non-existing" 2026/02/05 01:10:38 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2026/02/05 01:10:38 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:10:38 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.75s) === RUN TestCopyNoTraverse run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:10:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:10:39 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:10:39 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:10:42 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:10:42 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 (25.94s) === RUN TestCopyNoTraverseDeadlock run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", 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 (11.15s) === RUN TestCopyCheckFirst run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:11:16 INFO : drime root 'rclone-test-dohugah5vuru': Running all checks before starting transfers 2026/02/05 01:11:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:11:16 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:11:16 INFO : drime root 'rclone-test-dohugah5vuru': Checks finished, now starting transfers 2026/02/05 01:11:16 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:11:20 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:11:20 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 (28.17s) === RUN TestSyncNoTraverse run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:11:44 ERROR : Ignoring --no-traverse with sync 2026/02/05 01:11:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:11:45 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:11:45 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:11:47 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:11:47 INFO : sub dir/hello world: Copied (new) 2026/02/05 01:11:47 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 (27.94s) === RUN TestCopyWithDepth run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:12:13 DEBUG : hello world2: Need to transfer - File not found at Destination 2026/02/05 01:12:13 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:12:13 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:12:20 DEBUG : hello world2: size = 12 OK 2026/02/05 01:12:20 INFO : hello world2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestCopyWithDepth (29.78s) === RUN TestCopyWithFilesFrom run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:12:42 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 01:12:42 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:12:42 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:12:44 DEBUG : potato2: size = 11 OK 2026/02/05 01:12:44 INFO : potato2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestCopyWithFilesFrom (22.54s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:13:04 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 01:13:04 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:13:04 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:13:11 DEBUG : potato2: size = 11 OK 2026/02/05 01:13:11 INFO : potato2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyWithFilesFromAndNoTraverse Messages: listing wrong, want got potato2 (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyWithFilesFromAndNoTraverse Messages: Unexpected file "potato2" --- FAIL: TestCopyWithFilesFromAndNoTraverse (15.97s) === RUN TestCopyEmptyDirectories run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:13:20 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/05 01:13:20 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/05 01:13:20 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/02/05 01:13:20 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/05 01:13:21 INFO : sub dir2: Making directory 2026/02/05 01:13:21 INFO : sub dir: Making directory 2026/02/05 01:13:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:13:22 INFO : sub dir2/sub sub dir2: Making directory 2026/02/05 01:13:22 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:13:22 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:13:25 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:13:25 INFO : sub dir/hello world: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:358 Error: Should be true Test: TestCopyEmptyDirectories Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:358 Error: Not equal: expected: 0 actual : 1 Test: TestCopyEmptyDirectories Messages: 1 objects not found 2026/02/05 01:13:34 DEBUG : drime root 'rclone-test-dohugah5vuru': 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 (29.14s) === RUN TestCopyNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:13:49 INFO : sub dir2: Making directory 2026/02/05 01:13:49 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/05 01:13:49 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/05 01:13:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:13:50 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:13:50 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:13:54 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:13:54 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: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: TestCopyNoEmptyDirectories Messages: directories --- FAIL: TestCopyNoEmptyDirectories (26.63s) === RUN TestMoveEmptyDirectories run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:14:16 DEBUG : sub dir2: Making directory with metadata 2026/02/05 01:14:16 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/05 01:14:16 INFO : sub dir2: Making directory 2026/02/05 01:14:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:14:17 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:14:17 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:14:23 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:14:23 INFO : sub dir/hello world: Copied (new) 2026/02/05 01:14:23 INFO : sub dir/hello world: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/05 01:14:35 DEBUG : drime root 'rclone-test-dohugah5vuru': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2026/02/05 01:14:35 DEBUG : drime root 'rclone-test-dohugah5vuru': 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 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir", "sub dir2"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=8) "sub dir2" } Test: TestMoveEmptyDirectories Messages: directories --- FAIL: TestMoveEmptyDirectories (32.21s) === RUN TestSyncNoUpdateDirModtime run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:438: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.81s) === RUN TestMoveNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:14:49 INFO : sub dir2: Making directory 2026/02/05 01:14:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:14:49 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:14:49 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:14:54 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:14:54 INFO : sub dir/hello world: Copied (new) 2026/02/05 01:14:54 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:485 Error: Should be true Test: TestMoveNoEmptyDirectories 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:485 Error: Not equal: expected: 0 actual : 1 Test: TestMoveNoEmptyDirectories 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:485 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: TestMoveNoEmptyDirectories Messages: directories run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestMoveNoEmptyDirectories 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: TestMoveNoEmptyDirectories 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: TestMoveNoEmptyDirectories Messages: directories --- FAIL: TestMoveNoEmptyDirectories (28.03s) === RUN TestSyncEmptyDirectories run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:15:17 DEBUG : sub dir2: Making directory with metadata 2026/02/05 01:15:17 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/05 01:15:17 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/05 01:15:18 INFO : sub dir2: Making directory 2026/02/05 01:15:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:15:18 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:15:18 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:15:21 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:15:21 INFO : sub dir/hello world: Copied (new) 2026/02/05 01:15:21 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516 Error: Should be true Test: TestSyncEmptyDirectories Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516 Error: Not equal: expected: 0 actual : 1 Test: TestSyncEmptyDirectories Messages: 1 objects not found fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516 Error: Not equal: expected: []string{"sub dir", "sub dir2"} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,4 +1,3 @@ -([]string) (len=2) { - (string) (len=7) "sub dir", - (string) (len=8) "sub dir2" +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestSyncEmptyDirectories Messages: directories 2026/02/05 01:15:30 DEBUG : drime root 'rclone-test-dohugah5vuru': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncEmptyDirectories Messages: listing wrong, want got sub dir/hello world (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncEmptyDirectories Messages: Unexpected file "sub dir/hello world" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir", "sub dir2"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=8) "sub dir2" } Test: TestSyncEmptyDirectories Messages: directories --- FAIL: TestSyncEmptyDirectories (27.92s) === RUN TestSyncSetDelayedModTimes run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1156 /usr/local/go/src/testing/testing.go:1132 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:537 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestSyncSetDelayedModTimes Messages: directories --- FAIL: TestSyncSetDelayedModTimes (10.79s) === RUN TestSyncNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:15:56 INFO : sub dir2: Making directory 2026/02/05 01:15:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:15:56 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:15:56 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:16:01 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:16:01 INFO : sub dir/hello world: Copied (new) 2026/02/05 01:16:01 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:599 Error: Should be true Test: TestSyncNoEmptyDirectories Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:599 Error: Not equal: expected: 0 actual : 1 Test: TestSyncNoEmptyDirectories Messages: 1 objects not found fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:599 Error: Not equal: expected: []string{"sub dir"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,3 +1,2 @@ -([]string) (len=1) { - (string) (len=7) "sub dir" +([]string) { } Test: TestSyncNoEmptyDirectories Messages: directories run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncNoEmptyDirectories Messages: listing wrong, want got sub dir/hello world (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncNoEmptyDirectories Messages: Unexpected file "sub dir/hello world" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestSyncNoEmptyDirectories Messages: directories --- FAIL: TestSyncNoEmptyDirectories (27.26s) === RUN TestServerSideCopy run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", 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/05 01:16:41 DEBUG : Creating backend with remote "TestDrime:rclone-test-neyoxet6quye" sync_test.go:620: Server side copy (if possible) drime root 'rclone-test-dohugah5vuru' -> drime root 'rclone-test-neyoxet6quye' 2026/02/05 01:16:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:16:43 DEBUG : drime root 'rclone-test-neyoxet6quye': Waiting for checks to finish 2026/02/05 01:16:43 DEBUG : drime root 'rclone-test-neyoxet6quye': Waiting for transfers to finish 2026/02/05 01:16:45 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:16:45 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/05 01:16:45 DEBUG : drime root 'rclone-test-neyoxet6quye': 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.95s) === RUN TestCopyOverSelf run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:17:11 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone23648990) 2026/02/05 01:17:11 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 01:17:11 DEBUG : sub dir/hello world: Sizes differ 2026/02/05 01:17:11 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:17:11 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:17:13 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/05 01:17:13 DEBUG : sub dir/hello world: size = 17 OK 2026/02/05 01:17:13 INFO : sub dir/hello world: Copied (replaced existing) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:643 Error: Should be true Test: TestCopyOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (11) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:643 Error: Not equal: expected: 17 actual : 11 Test: TestCopyOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyOverSelf Messages: listing wrong, want got sub dir/hello world (17) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCopyOverSelf Messages: Unexpected file "sub dir/hello world" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestCopyOverSelf Messages: directories --- FAIL: TestCopyOverSelf (36.68s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", 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/05 01:17:55 DEBUG : Creating backend with remote "TestDrime:rclone-test-qasumek3fayo" sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-dohugah5vuru' -> drime root 'rclone-test-qasumek3fayo' 2026/02/05 01:17:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:17:57 DEBUG : drime root 'rclone-test-qasumek3fayo': Waiting for checks to finish 2026/02/05 01:17:57 DEBUG : drime root 'rclone-test-qasumek3fayo': Waiting for transfers to finish 2026/02/05 01:17:59 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:17:59 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/05 01:18:01 DEBUG : sub dir/hello world: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:665 Error: Should be true Test: TestServerSideCopyOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (11) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:665 Error: Not equal: expected: 17 actual : 11 Test: TestServerSideCopyOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 2026/02/05 01:18:11 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 01:18:11 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-qasumek3fayo') 2026/02/05 01:18:11 DEBUG : sub dir/hello world: Sizes differ 2026/02/05 01:18:11 DEBUG : drime root 'rclone-test-qasumek3fayo': Waiting for checks to finish 2026/02/05 01:18:11 DEBUG : drime root 'rclone-test-qasumek3fayo': Waiting for transfers to finish 2026/02/05 01:18:12 DEBUG : sub dir/hello world: Server side copy: removing existing object after successful copy 2026/02/05 01:18:12 DEBUG : sub dir/hello world: size = 17 OK 2026/02/05 01:18:12 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/05 01:18:22 DEBUG : drime root 'rclone-test-qasumek3fayo': 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 (60.02s) === RUN TestMoveOverSelf run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", 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/05 01:18:49 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone23648990) 2026/02/05 01:18:49 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 01:18:49 DEBUG : sub dir/hello world: Sizes differ 2026/02/05 01:18:49 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:18:49 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:18:52 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/05 01:18:53 DEBUG : sub dir/hello world: size = 17 OK 2026/02/05 01:18:53 INFO : sub dir/hello world: Copied (replaced existing) 2026/02/05 01:18:53 INFO : sub dir/hello world: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:688 Error: Should be true Test: TestMoveOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (11) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:688 Error: Not equal: expected: 17 actual : 11 Test: TestMoveOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestMoveOverSelf Messages: listing wrong, want got sub dir/hello world (17) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestMoveOverSelf Messages: Unexpected file "sub dir/hello world" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestMoveOverSelf Messages: directories --- FAIL: TestMoveOverSelf (39.89s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", 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/05 01:19:36 DEBUG : Creating backend with remote "TestDrime:rclone-test-xoxican3tufo" sync_test.go:701: Server side copy (if possible) drime root 'rclone-test-dohugah5vuru' -> drime root 'rclone-test-xoxican3tufo' 2026/02/05 01:19:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:19:37 DEBUG : drime root 'rclone-test-xoxican3tufo': Waiting for checks to finish 2026/02/05 01:19:37 DEBUG : drime root 'rclone-test-xoxican3tufo': Waiting for transfers to finish 2026/02/05 01:19:39 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 01:19:39 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/05 01:19:47 DEBUG : sub dir/hello world: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/05 01:19:56 DEBUG : drime root 'rclone-test-xoxican3tufo': Using server-side directory move 2026/02/05 01:19:56 INFO : drime root 'rclone-test-xoxican3tufo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/05 01:19:56 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 01:19:56 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-xoxican3tufo') 2026/02/05 01:19:56 DEBUG : sub dir/hello world: Sizes differ 2026/02/05 01:19:56 DEBUG : drime root 'rclone-test-xoxican3tufo': Waiting for checks to finish 2026/02/05 01:19:56 DEBUG : drime root 'rclone-test-xoxican3tufo': Waiting for transfers to finish 2026/02/05 01:19:57 INFO : sub dir/hello world: Deleted 2026/02/05 01:19:57 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/05 01:19:57 DEBUG : drime root 'rclone-test-xoxican3tufo': Purge remote 2026/02/05 01:19:59 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:19:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 01:20:00 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:20:00 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 01:20:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:20:00 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 01:20:00 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:20:00 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 01:20:00 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:20:00 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 01:20:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:20:01 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 01:20:01 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:20:01 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 01:20:02 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:20:02 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 01:20:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:20:03 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 01:20:05 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 01:20:05 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/05 01:20:11 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:20:11 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 01:20:21 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:20:41 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:21:01 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:21:21 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:21:41 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:22:01 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:22:21 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:22:41 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:23:01 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/05 01:23:21 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:23:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:24:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:24:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:24:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:25:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:25:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:25:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:26:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:26:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "sub dir/hello world" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "sub dir/hello world" failed: failed to delete item: Error "Server Error" 2026/02/05 01:26:41 DEBUG : pacer: Reducing sleep to 10s 2026/02/05 01:27:01 DEBUG : pacer: Reducing sleep to 5s 2026/02/05 01:27:11 DEBUG : pacer: Reducing sleep to 2.5s --- FAIL: TestServerSideMoveOverSelf (475.77s) === RUN TestCopyAfterDelete run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:27:16 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/05 01:27:19 DEBUG : pacer: Reducing sleep to 625ms 2026/02/05 01:27:20 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/05 01:27:24 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/05 01:27:24 ERROR : error listing: directory not found 2026/02/05 01:27:24 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/05 01:27:24 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/05 01:27:24 INFO : Local file system at /tmp/rclone23648990: Making directory 2026/02/05 01:27:25 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/05 01:27:25 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 01:27:25 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:27:25 DEBUG : drime root 'rclone-test-dohugah5vuru': 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.71s) === RUN TestCopyRedownload run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:27:41 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:27:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 01:27:41 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 01:27:42 DEBUG : pacer: low level retry 1/1 (error Error "Server Error") 2026/02/05 01:27:42 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:286: Retry Put of "sub dir/hello world" to drime root 'rclone-test-dohugah5vuru': 1/10 (failed to upload file: Error "Server Error") 2026/02/05 01:27:44 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 01:27:47 DEBUG : Added delayed dir = "sub dir", newDst= 2026/02/05 01:27:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 01:27:48 DEBUG : Local file system at /tmp/rclone23648990: Waiting for checks to finish 2026/02/05 01:27:48 DEBUG : Local file system at /tmp/rclone23648990: Waiting for transfers to finish 2026/02/05 01:27:48 DEBUG : sub dir/hello world.9076d4bf.partial: size = 11 OK 2026/02/05 01:27:48 DEBUG : sub dir/hello world.9076d4bf.partial: renamed to: sub dir/hello world 2026/02/05 01:27:48 INFO : sub dir/hello world: Copied (new) 2026/02/05 01:27:48 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 (22.14s) === RUN TestSyncBasedOnCheckSum run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:28:03 DEBUG : check sum: Need to transfer - File not found at Destination 2026/02/05 01:28:03 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:28:03 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:28:06 DEBUG : check sum: size = 1 OK 2026/02/05 01:28:06 INFO : check sum: Copied (new) 2026/02/05 01:28:06 DEBUG : Waiting for deletions to finish 2026/02/05 01:28:06 INFO : sub dir: Removing directory 2026/02/05 01:28:07 DEBUG : drime root 'rclone-test-dohugah5vuru': deleted 1 directories fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/05 01:28:15 DEBUG : check sum: size = 1 OK 2026/02/05 01:28:15 NOTICE: drime root 'rclone-test-dohugah5vuru': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/02/05 01:28:15 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:28:15 DEBUG : check sum: Size of src and dst objects identical 2026/02/05 01:28:15 DEBUG : check sum: Unchanged skipping 2026/02/05 01:28:15 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:28:15 DEBUG : Waiting for deletions to finish 2026/02/05 01:28:15 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestSyncBasedOnCheckSum (25.32s) === RUN TestSyncSizeOnly run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:28:28 DEBUG : sizeonly: Need to transfer - File not found at Destination 2026/02/05 01:28:28 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:28:28 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:28:31 DEBUG : sizeonly: size = 6 OK 2026/02/05 01:28:31 INFO : sizeonly: Copied (new) 2026/02/05 01:28:31 DEBUG : Waiting for deletions to finish 2026/02/05 01:28:31 DEBUG : sizeonly: size = 6 OK 2026/02/05 01:28:31 DEBUG : sizeonly: Sizes identical 2026/02/05 01:28:31 DEBUG : sizeonly: Unchanged skipping 2026/02/05 01:28:31 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:28:31 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:28:31 DEBUG : Waiting for deletions to finish 2026/02/05 01:28:31 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 (13.44s) === RUN TestSyncIgnoreSize run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:28:42 DEBUG : ignore-size: Need to transfer - File not found at Destination 2026/02/05 01:28:42 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:28:42 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:28:46 INFO : ignore-size: Copied (new) 2026/02/05 01:28:46 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/05 01:28:55 DEBUG : ignore-size: Sizes identical 2026/02/05 01:28:55 DEBUG : ignore-size: Unchanged skipping 2026/02/05 01:28:55 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:28:55 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:28:55 DEBUG : Waiting for deletions to finish 2026/02/05 01:28:55 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 (23.58s) === RUN TestSyncIgnoreTimes run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:29:10 DEBUG : existing: size = 6 OK 2026/02/05 01:29:10 DEBUG : existing: Sizes identical 2026/02/05 01:29:10 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:29:10 DEBUG : existing: Unchanged skipping 2026/02/05 01:29:10 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:29:10 DEBUG : Waiting for deletions to finish 2026/02/05 01:29:10 INFO : There was nothing to transfer 2026/02/05 01:29:11 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2026/02/05 01:29:11 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:29:11 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:29:19 DEBUG : existing: Removing old object on successful upload 2026/02/05 01:29:19 DEBUG : existing: size = 6 OK 2026/02/05 01:29:19 INFO : existing: Copied (replaced existing) 2026/02/05 01:29:19 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 (24.53s) === RUN TestSyncIgnoreExisting run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:29:29 DEBUG : existing: Destination exists, skipping 2026/02/05 01:29:29 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:29:29 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:29:29 DEBUG : Waiting for deletions to finish 2026/02/05 01:29:29 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/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:929 Error: Should be true Test: TestSyncIgnoreExisting Messages: listing wrong, want existing (6) got fstest.go:203: Not found "existing" 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:929 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreExisting Messages: 1 objects not found 2026/02/05 01:29:39 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/05 01:29:39 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:29:39 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:29:41 DEBUG : existing: size = 11 OK 2026/02/05 01:29:41 INFO : existing: Copied (new) 2026/02/05 01:29:41 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:939 Error: Should be true Test: TestSyncIgnoreExisting Messages: listing wrong, want existing (6) got existing (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:939 Error: Not equal: expected: 6 actual : 11 Test: TestSyncIgnoreExisting Messages: existing: size incorrect file=6 vs obj=11 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 --- FAIL: TestSyncIgnoreExisting (28.73s) === RUN TestSyncIgnoreErrors run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:30:04 INFO : d: Making directory 2026/02/05 01:30:06 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/05 01:30:06 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:30:06 DEBUG : c/non empty space: size = 5 OK 2026/02/05 01:30:06 DEBUG : c/non empty space: Sizes identical 2026/02/05 01:30:06 DEBUG : c/non empty space: Unchanged skipping 2026/02/05 01:30:06 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:30:09 DEBUG : a/potato2: size = 60 OK 2026/02/05 01:30:09 INFO : a/potato2: Copied (new) 2026/02/05 01:30:09 DEBUG : Waiting for deletions to finish 2026/02/05 01:30:09 INFO : b/potato: Deleted 2026/02/05 01:30:09 INFO : d: Removing directory 2026/02/05 01:30:10 INFO : b: Removing directory 2026/02/05 01:30:11 DEBUG : b: Failed to Rmdir: directory not empty 2026/02/05 01:30:11 DEBUG : drime root 'rclone-test-dohugah5vuru': failed to delete 1 directories 2026/02/05 01:30:11 DEBUG : drime root 'rclone-test-dohugah5vuru': deleted 1 directories fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:994 Error: Not equal: expected: []string{"a", "c"} actual : []string{"a", "b", "c"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=2) { +([]string) (len=3) { (string) (len=1) "a", + (string) (len=1) "b", (string) (len=1) "c" Test: TestSyncIgnoreErrors Messages: directories run.go:130: removing dir "c" failed - try 1/3: directory not empty run.go:130: removing dir "c" failed - try 2/3: directory not empty run.go:130: removing dir "c" failed - try 3/3: directory not empty run.go:133: removing dir "c" failed: directory not empty 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{"c"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=1) "c" } Test: TestSyncIgnoreErrors Messages: directories --- FAIL: TestSyncIgnoreErrors (39.18s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:30:41 DEBUG : empty space: size = 1 OK 2026/02/05 01:30:41 DEBUG : empty space: Sizes identical 2026/02/05 01:30:41 DEBUG : empty space: Unchanged skipping 2026/02/05 01:30:42 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:30:42 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:30:42 DEBUG : Waiting for deletions to finish 2026/02/05 01:30:42 NOTICE: c: Skipped remove directory as --dry-run is set 2026/02/05 01:30:42 DEBUG : drime root 'rclone-test-dohugah5vuru': deleted 1 directories 2026/02/05 01:30:42 INFO : There was nothing to transfer 2026/02/05 01:30:42 DEBUG : empty space: size = 1 OK 2026/02/05 01:30:42 DEBUG : empty space: Sizes identical 2026/02/05 01:30:42 DEBUG : empty space: Unchanged skipping 2026/02/05 01:30:43 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:30:43 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:30:43 DEBUG : Waiting for deletions to finish 2026/02/05 01:30:43 INFO : c: Removing directory 2026/02/05 01:30:43 DEBUG : drime root 'rclone-test-dohugah5vuru': deleted 1 directories 2026/02/05 01:30:43 INFO : There was nothing to transfer 2026/02/05 01:30:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:30:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 01:30:46 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:30:46 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 01:30:46 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:30:46 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 01:30:46 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:30:46 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 01:30:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:30:47 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 01:30:47 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:30:47 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 01:30:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:30:48 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 01:30:48 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:30:48 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 01:30:50 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:30:50 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 01:30:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 01:30:52 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/05 01:30:57 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/05 01:31:08 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:31:08 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/05 01:31:13 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:31:13 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 01:31:23 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:31:43 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:32:03 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:32:23 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:32:43 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:33:03 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:33:23 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:33:43 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/05 01:34:03 DEBUG : pacer: Reducing sleep to 10s 2026/02/05 01:34:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:34:23 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 01:34:33 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:34:53 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:35:13 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:35:33 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:35:53 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:36:13 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:36:33 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:36:53 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:37:13 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/05 01:37:33 DEBUG : pacer: Reducing sleep to 10s --- PASS: TestSyncAfterChangingModtimeOnly (415.96s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:1046: Can't check this if no hashes supported 2026/02/05 01:37:53 DEBUG : pacer: Reducing sleep to 5s 2026/02/05 01:38:03 DEBUG : pacer: Reducing sleep to 2.5s --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (30.03s) === RUN TestSyncDoesntUpdateModtime run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:1072: Can't run this test on fs which doesn't support mod time 2026/02/05 01:38:08 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/05 01:38:10 DEBUG : pacer: Reducing sleep to 625ms --- SKIP: TestSyncDoesntUpdateModtime (7.49s) === RUN TestSyncAfterAddingAFile run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:38:12 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/05 01:38:14 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/05 01:38:15 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/05 01:38:15 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/05 01:38:15 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/05 01:38:15 DEBUG : empty space: size = 1 OK 2026/02/05 01:38:15 DEBUG : empty space: Sizes identical 2026/02/05 01:38:15 DEBUG : empty space: Unchanged skipping 2026/02/05 01:38:15 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:38:15 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:38:15 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/05 01:38:19 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 01:38:19 DEBUG : potato: size = 60 OK 2026/02/05 01:38:19 INFO : potato: Copied (new) 2026/02/05 01:38:19 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:1109 Error: Should be true Test: TestSyncAfterAddingAFile Messages: listing wrong, want empty space (1), potato (60) got empty space (1) fstest.go:203: Not found "potato" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1109 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterAddingAFile Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncAfterAddingAFile Messages: listing wrong, want got empty space (1), potato (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncAfterAddingAFile Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncAfterAddingAFile Messages: Unexpected file "potato" --- FAIL: TestSyncAfterAddingAFile (26.64s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:38:40 DEBUG : potato: Removing old object on successful upload 2026/02/05 01:38:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:38:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 01:38:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:38:41 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 01:38:41 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:38:41 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 01:38:41 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:38:41 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 01:38:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:38:42 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 01:38:42 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:38:42 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 01:38:42 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:38:42 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 01:38:43 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:38:43 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 01:38:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:38:44 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 01:38:47 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 01:38:47 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:286: Retry Put of "potato" to drime root 'rclone-test-dohugah5vuru': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error") 2026/02/05 01:38:52 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/05 01:39:07 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/05 01:39:07 DEBUG : potato: Removing old object on successful upload 2026/02/05 01:39:07 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/05 01:39:10 DEBUG : pacer: Reducing sleep to 640ms 2026/02/05 01:39:11 DEBUG : pacer: Reducing sleep to 320ms 2026/02/05 01:39:11 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:39:11 DEBUG : potato: size = 21 (Local file system at /tmp/rclone23648990) 2026/02/05 01:39:11 DEBUG : potato: size = 60 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 01:39:11 DEBUG : potato: Sizes differ 2026/02/05 01:39:11 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:39:17 DEBUG : pacer: Reducing sleep to 160ms 2026/02/05 01:39:17 DEBUG : potato: Removing old object on successful upload 2026/02/05 01:39:17 DEBUG : pacer: Reducing sleep to 80ms 2026/02/05 01:39:17 DEBUG : potato: size = 21 OK 2026/02/05 01:39:17 INFO : potato: Copied (replaced existing) 2026/02/05 01:39:17 DEBUG : Waiting for deletions to finish 2026/02/05 01:39:18 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/05 01:39:19 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 01:39:20 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestSyncAfterChangingFilesSizeOnly (64.61s) === RUN TestSyncAfterChangingContentsOnly run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:1136: ModTimeNotSupported so forcing file to be a different size fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/05 01:39:52 DEBUG : potato: size = 21 (Local file system at /tmp/rclone23648990) 2026/02/05 01:39:52 DEBUG : potato: size = 36 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 01:39:52 DEBUG : potato: Sizes differ 2026/02/05 01:39:52 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:39:52 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:39:54 DEBUG : potato: Removing old object on successful upload 2026/02/05 01:39:54 DEBUG : potato: size = 21 OK 2026/02/05 01:39:54 INFO : potato: Copied (replaced existing) 2026/02/05 01:39:54 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestSyncAfterChangingContentsOnly (32.65s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:40:22 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 01:40:22 DEBUG : empty space: size = 1 OK 2026/02/05 01:40:22 DEBUG : empty space: Sizes identical 2026/02/05 01:40:22 DEBUG : empty space: Unchanged skipping 2026/02/05 01:40:22 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:40:22 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:40:22 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2026/02/05 01:40:22 DEBUG : Waiting for deletions to finish 2026/02/05 01:40:22 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 (16.63s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:40:33 DEBUG : potato: Removing old object on successful upload 2026/02/05 01:40:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:40:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 01:40:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:40:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 01:40:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:40:34 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 01:40:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:40:34 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 01:40:34 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:40:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 01:40:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:40:35 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 01:40:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:40:35 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 01:40:36 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:40:36 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 01:40:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:40:37 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 01:40:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 01:40:40 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:286: Retry Put of "potato" to drime root 'rclone-test-dohugah5vuru': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error") 2026/02/05 01:40:45 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/05 01:40:58 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/05 01:40:58 DEBUG : potato: Removing old object on successful upload 2026/02/05 01:41:00 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/05 01:41:03 DEBUG : pacer: Reducing sleep to 640ms 2026/02/05 01:41:07 DEBUG : pacer: Reducing sleep to 320ms 2026/02/05 01:41:07 DEBUG : pacer: Reducing sleep to 160ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/05 01:41:09 DEBUG : pacer: Reducing sleep to 80ms 2026/02/05 01:41:09 DEBUG : pacer: Reducing sleep to 40ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/05 01:41:11 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 01:41:12 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: listing wrong, want empty space (1), potato (21) got potato (21) fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: 1 objects not found 2026/02/05 01:41:16 DEBUG : empty space: size = 1 OK 2026/02/05 01:41:16 DEBUG : empty space: Sizes identical 2026/02/05 01:41:16 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 01:41:16 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:41:16 DEBUG : empty space: Unchanged skipping 2026/02/05 01:41:16 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:41:22 DEBUG : potato2: size = 60 OK 2026/02/05 01:41:22 INFO : potato2: Copied (new) 2026/02/05 01:41:22 DEBUG : Waiting for deletions to finish 2026/02/05 01:41:22 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: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: TestSyncAfterRemovingAFileAndAddingAFile (74.42s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:41:52 INFO : d: Making directory 2026/02/05 01:41:53 INFO : d/e: Making directory 2026/02/05 01:41:55 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/05 01:41:56 DEBUG : c/non empty space: size = 5 OK 2026/02/05 01:41:56 DEBUG : c/non empty space: Sizes identical 2026/02/05 01:41:56 DEBUG : c/non empty space: Unchanged skipping 2026/02/05 01:41:56 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:41:56 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:41:58 DEBUG : a/potato2: size = 60 OK 2026/02/05 01:41:58 INFO : a/potato2: Copied (new) 2026/02/05 01:41:58 DEBUG : Waiting for deletions to finish 2026/02/05 01:41:59 INFO : b/potato: Deleted 2026/02/05 01:41:59 INFO : d/e: Removing directory 2026/02/05 01:41:59 INFO : d: Removing directory 2026/02/05 01:41:59 DEBUG : d: Failed to Rmdir: directory not empty 2026/02/05 01:41:59 INFO : b: Removing directory 2026/02/05 01:42:00 DEBUG : b: Failed to Rmdir: directory not empty 2026/02/05 01:42:00 DEBUG : drime root 'rclone-test-dohugah5vuru': failed to delete 2 directories 2026/02/05 01:42:00 DEBUG : drime root 'rclone-test-dohugah5vuru': 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.05s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:42:32 INFO : d: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1285 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: listing wrong, want b/potato (21), c/non empty space (5) got b/potato (21) fstest.go:203: Not found "c/non empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1285 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: 1 objects not found 2026/02/05 01:42:42 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/05 01:42:43 DEBUG : c/non empty space: size = 5 OK 2026/02/05 01:42:43 DEBUG : c/non empty space: Sizes identical 2026/02/05 01:42:43 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:42:43 DEBUG : c/non empty space: Unchanged skipping 2026/02/05 01:42:43 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:42:52 DEBUG : a/potato2: size = 60 OK 2026/02/05 01:42:52 INFO : a/potato2: Copied (new) 2026/02/05 01:42:52 ERROR : drime root 'rclone-test-dohugah5vuru': not deleting files as there were IO errors 2026/02/05 01:42:52 ERROR : drime root 'rclone-test-dohugah5vuru': not deleting directories as there were IO errors run.go:130: removing dir "c" failed - try 1/3: directory not empty run.go:130: removing dir "c" failed - try 2/3: directory not empty run.go:130: removing dir "c" failed - try 3/3: directory not empty run.go:133: removing dir "c" failed: directory not empty run.go:130: removing dir "b" failed - try 1/3: directory not empty run.go:130: removing dir "b" failed - try 2/3: directory not empty run.go:130: removing dir "b" failed - try 3/3: directory not empty run.go:133: removing dir "b" failed: directory not empty 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 (47.39s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:43:31 DEBUG : Waiting for deletions to finish 2026/02/05 01:43:32 DEBUG : empty space: size = 1 OK 2026/02/05 01:43:32 DEBUG : empty space: Sizes identical 2026/02/05 01:43:32 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 01:43:32 DEBUG : empty space: Unchanged skipping 2026/02/05 01:43:32 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:43:32 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:43:32 INFO : potato: Deleted 2026/02/05 01:43:36 DEBUG : potato2: size = 60 OK 2026/02/05 01:43:36 INFO : potato2: Copied (new) 2026/02/05 01:43:36 INFO : c: Removing directory 2026/02/05 01:43:36 INFO : b: Removing directory 2026/02/05 01:43:37 DEBUG : drime root 'rclone-test-dohugah5vuru': 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: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: TestSyncDeleteDuring (48.36s) === RUN TestSyncDeleteBefore run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncDeleteBefore Messages: listing wrong, want empty space (1), potato (21) got potato (21) fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 Error: Not equal: expected: 0 actual : 1 Test: TestSyncDeleteBefore Messages: 1 objects not found 2026/02/05 01:44:18 DEBUG : Waiting for deletions to finish 2026/02/05 01:44:18 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:44:18 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:44:18 INFO : potato: Deleted 2026/02/05 01:44:19 DEBUG : empty space: size = 1 OK 2026/02/05 01:44:19 DEBUG : empty space: Sizes identical 2026/02/05 01:44:19 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 01:44:19 DEBUG : empty space: Unchanged skipping 2026/02/05 01:44:19 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:44:19 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:44:24 DEBUG : potato2: size = 60 OK 2026/02/05 01:44:24 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 (46.60s) === RUN TestCopyDeleteBefore run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", 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/05 01:44:59 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 01:44:59 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:44:59 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:45:01 DEBUG : potato2: size = 19 OK 2026/02/05 01:45:01 INFO : potato2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestCopyDeleteBefore (34.13s) === RUN TestSyncWithExclude run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:45:26 DEBUG : enormous: Excluded (Size Filter) 2026/02/05 01:45:26 DEBUG : enormous: Excluded 2026/02/05 01:45:26 DEBUG : potato2: Excluded (Size Filter) 2026/02/05 01:45:26 DEBUG : potato2: Excluded 2026/02/05 01:45:27 DEBUG : potato2: Excluded (Size Filter) 2026/02/05 01:45:27 DEBUG : potato2: Excluded 2026/02/05 01:45:27 DEBUG : empty space: size = 1 OK 2026/02/05 01:45:27 DEBUG : empty space: Sizes identical 2026/02/05 01:45:27 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:45:27 DEBUG : empty space: Unchanged skipping 2026/02/05 01:45:27 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:45:27 DEBUG : Waiting for deletions to finish 2026/02/05 01:45:27 INFO : There was nothing to transfer 2026/02/05 01:45:27 DEBUG : enormous: Excluded (Size Filter) 2026/02/05 01:45:27 DEBUG : enormous: Excluded 2026/02/05 01:45:27 DEBUG : potato2: Excluded (Size Filter) 2026/02/05 01:45:27 DEBUG : potato2: Excluded 2026/02/05 01:45:27 DEBUG : potato2: Excluded (Size Filter) 2026/02/05 01:45:27 DEBUG : potato2: Excluded 2026/02/05 01:45:27 DEBUG : empty space: size = 1 OK 2026/02/05 01:45:27 DEBUG : empty space: Sizes identical 2026/02/05 01:45:27 DEBUG : empty space: Unchanged skipping 2026/02/05 01:45:27 DEBUG : Local file system at /tmp/rclone23648990: Waiting for checks to finish 2026/02/05 01:45:27 DEBUG : Local file system at /tmp/rclone23648990: Waiting for transfers to finish 2026/02/05 01:45:27 DEBUG : Waiting for deletions to finish 2026/02/05 01:45:27 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncWithExclude Messages: listing wrong, want got empty space (1), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncWithExclude Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestSyncWithExclude Messages: Unexpected file "potato2" --- FAIL: TestSyncWithExclude (14.52s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 01:45:55 DEBUG : enormous: Excluded (Size Filter) 2026/02/05 01:45:55 DEBUG : enormous: Excluded 2026/02/05 01:45:55 DEBUG : potato2: Excluded (Size Filter) 2026/02/05 01:45:55 DEBUG : potato2: Excluded 2026/02/05 01:45:55 DEBUG : empty space: size = 1 OK 2026/02/05 01:45:55 DEBUG : empty space: Sizes identical 2026/02/05 01:45:55 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 01:45:55 DEBUG : empty space: Unchanged skipping 2026/02/05 01:45:55 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 01:45:55 DEBUG : Waiting for deletions to finish 2026/02/05 01:45:56 INFO : potato2: Deleted 2026/02/05 01:45:56 INFO : enormous: Deleted 2026/02/05 01:45:56 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/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:1436 Error: Should be true Test: TestSyncWithExcludeAndDeleteExcluded Messages: listing wrong, want empty space (1) got empty space (1), enormous (100), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1436 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncWithExcludeAndDeleteExcluded Messages: Unexpected file "enormous" 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:1436 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncWithExcludeAndDeleteExcluded Messages: Unexpected file "potato2" 2026/02/05 01:46:04 DEBUG : enormous: Excluded (Size Filter) 2026/02/05 01:46:04 DEBUG : enormous: Excluded 2026/02/05 01:46:04 DEBUG : potato2: Excluded (Size Filter) 2026/02/05 01:46:04 DEBUG : potato2: Excluded 2026/02/05 01:46:04 DEBUG : empty space: size = 1 OK 2026/02/05 01:46:04 DEBUG : empty space: Sizes identical 2026/02/05 01:46:04 DEBUG : empty space: Unchanged skipping 2026/02/05 01:46:04 DEBUG : Local file system at /tmp/rclone23648990: Waiting for checks to finish 2026/02/05 01:46:04 DEBUG : Local file system at /tmp/rclone23648990: Waiting for transfers to finish 2026/02/05 01:46:04 DEBUG : Waiting for deletions to finish 2026/02/05 01:46:04 INFO : enormous: Deleted 2026/02/05 01:46:04 INFO : potato2: Deleted 2026/02/05 01:46:04 INFO : There was nothing to transfer 2026/02/05 01:46:05 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:46:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 01:46:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:46:05 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 01:46:06 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:46:06 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 01:46:06 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:46:06 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 01:46:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:46:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 01:46:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:46:07 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 01:46:07 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:46:07 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 01:46:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:46:08 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 01:46:09 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:46:09 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 01:46:12 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 01:46:12 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "enormous" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/05 01:46:17 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:46:17 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 01:46:27 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:46:47 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:47:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:47:27 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:47:47 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:48:07 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:48:27 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:48:47 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:49:07 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "enormous" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/05 01:49:27 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:49:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:50:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:50:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:50:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:51:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:51:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:51:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:52:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:52:27 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "enormous" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "enormous" failed: failed to delete item: Error "Server Error" 2026/02/05 01:52:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:53:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:53:27 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:53:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:54:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:54:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:54:47 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:55:07 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:55:27 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:55:47 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/05 01:56:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:56:27 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 01:56:47 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 01:57:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 01:57:27 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 01:57:47 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 01:58:07 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 01:58:27 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 01:58:47 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 01:59:07 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/05 01:59:27 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 01:59:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:00:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:00:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:00:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:01:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:01:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:01:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:02:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:02:27 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/05 02:02:47 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestSyncWithExcludeAndDeleteExcluded (1030.66s) === RUN TestSyncWithUpdateOlder run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:1454: Can't run this test on fs which doesn't support mod time 2026/02/05 02:03:07 DEBUG : pacer: Reducing sleep to 5s 2026/02/05 02:03:17 DEBUG : pacer: Reducing sleep to 2.5s --- SKIP: TestSyncWithUpdateOlder (29.93s) === 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-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:1569: Can track renames: false 2026/02/05 02:03:17 ERROR : drime root 'rclone-test-dohugah5vuru': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/05 02:03:22 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/05 02:03:22 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/05 02:03:22 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/05 02:03:22 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:03:22 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:03:25 DEBUG : pacer: Reducing sleep to 625ms 2026/02/05 02:03:26 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/05 02:03:34 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/05 02:03:34 DEBUG : yam: size = 11 OK 2026/02/05 02:03:34 INFO : yam: Copied (new) 2026/02/05 02:03:34 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/05 02:03:34 DEBUG : potato: size = 14 OK 2026/02/05 02:03:34 INFO : potato: Copied (new) 2026/02/05 02:03:34 DEBUG : Waiting for deletions to finish 2026/02/05 02:03:34 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/05 02:03:34 ERROR : drime root 'rclone-test-dohugah5vuru': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/05 02:03:35 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/05 02:03:35 DEBUG : potato: size = 14 OK 2026/02/05 02:03:35 DEBUG : potato: Sizes identical 2026/02/05 02:03:35 DEBUG : yaml: Need to transfer - File not found at Destination 2026/02/05 02:03:35 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:03:35 DEBUG : potato: Unchanged skipping 2026/02/05 02:03:35 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:03:35 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 02:03:40 DEBUG : yaml: size = 11 OK 2026/02/05 02:03:40 INFO : yaml: Copied (new) 2026/02/05 02:03:40 DEBUG : Waiting for deletions to finish 2026/02/05 02:03:40 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: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: TestSyncWithTrackRenames (47.19s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:1641: Can track renames: false 2026/02/05 02:04:04 ERROR : drime root 'rclone-test-dohugah5vuru': Ignoring --track-renames as either the source or destination do not support modtime 2026/02/05 02:04:04 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/05 02:04:04 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/05 02:04:04 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:04:04 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:04:06 DEBUG : potato: size = 14 OK 2026/02/05 02:04:06 INFO : potato: Copied (new) 2026/02/05 02:04:07 DEBUG : yam: size = 11 OK 2026/02/05 02:04:07 INFO : yam: Copied (new) 2026/02/05 02:04:07 DEBUG : Waiting for deletions to finish 2026/02/05 02:04:08 ERROR : drime root 'rclone-test-dohugah5vuru': Ignoring --track-renames as either the source or destination do not support modtime 2026/02/05 02:04:08 DEBUG : potato: size = 14 OK 2026/02/05 02:04:08 DEBUG : potato: Sizes identical 2026/02/05 02:04:08 DEBUG : yaml: Need to transfer - File not found at Destination 2026/02/05 02:04:08 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:04:08 DEBUG : potato: Unchanged skipping 2026/02/05 02:04:08 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:04:10 DEBUG : yaml: size = 11 OK 2026/02/05 02:04:10 INFO : yaml: Copied (new) 2026/02/05 02:04:10 DEBUG : Waiting for deletions to finish 2026/02/05 02:04:11 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:1662 Error: Should be true Test: TestSyncWithTrackRenamesStrategyModtime 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:1662 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncWithTrackRenamesStrategyModtime 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:1662 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestSyncWithTrackRenamesStrategyModtime 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: TestSyncWithTrackRenamesStrategyModtime 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: TestSyncWithTrackRenamesStrategyModtime 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: TestSyncWithTrackRenamesStrategyModtime Messages: Unexpected file "yaml" --- FAIL: TestSyncWithTrackRenamesStrategyModtime (24.81s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" sync_test.go:1680: Can track renames: false 2026/02/05 02:04:30 DEBUG : potato: size = 14 OK 2026/02/05 02:04:30 DEBUG : potato: Sizes identical 2026/02/05 02:04:30 DEBUG : potato: Unchanged skipping 2026/02/05 02:04:30 INFO : drime root 'rclone-test-dohugah5vuru': Making map for --track-renames 2026/02/05 02:04:30 INFO : drime root 'rclone-test-dohugah5vuru': Finished making map for --track-renames 2026/02/05 02:04:30 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:04:30 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2026/02/05 02:04:30 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for renames to finish 2026/02/05 02:04:30 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:04:33 DEBUG : sub/yam: size = 11 OK 2026/02/05 02:04:33 INFO : sub/yam: Copied (new) 2026/02/05 02:04:33 DEBUG : Waiting for deletions to finish 2026/02/05 02:04:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:04:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 02:04:33 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:04:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 02:04:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:04:34 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 02:04:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:04:34 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 02:04:34 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:04:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 02:04:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:04:35 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 02:04:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:04:35 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 02:04:36 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:04:36 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 02:04:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:04:37 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 02:04:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 02:04:40 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/05 02:04:40 ERROR : yaml: Couldn't delete: failed to delete item: Error "Server Error" 2026/02/05 02:04:40 ERROR : drime root 'rclone-test-dohugah5vuru': not deleting directories as there were IO errors sync_test.go:1687: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1687 Error: Received unexpected error: failed to delete 1 files Test: TestSyncWithTrackRenamesStrategyLeaf 2026/02/05 02:04:45 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/05 02:04:55 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/05 02:05:00 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/05 02:05:03 DEBUG : pacer: Reducing sleep to 640ms run.go:130: removing dir "sub" failed - try 1/3: directory not empty 2026/02/05 02:05:04 DEBUG : pacer: Reducing sleep to 320ms run.go:130: removing dir "sub" failed - try 2/3: directory not empty 2026/02/05 02:05:05 DEBUG : pacer: Reducing sleep to 160ms 2026/02/05 02:05:06 DEBUG : pacer: Reducing sleep to 80ms 2026/02/05 02:05:06 DEBUG : pacer: Reducing sleep to 40ms --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (37.00s) === RUN TestServerSideMoveLocal run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 02:05:06 DEBUG : Creating backend with remote "/tmp/rclone23648990/dir1" 2026/02/05 02:05:06 DEBUG : Config file has changed externally - reloading 2026/02/05 02:05:06 DEBUG : Creating backend with remote "/tmp/rclone23648990/dir2" 2026/02/05 02:05:06 DEBUG : Local file system at /tmp/rclone23648990/dir2: Using server-side directory move 2026/02/05 02:05:06 INFO : Local file system at /tmp/rclone23648990/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/05 02:05:06 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/05 02:05:06 INFO : file1.txt: Moved (server-side) 2026/02/05 02:05:06 DEBUG : Local file system at /tmp/rclone23648990/dir2: Waiting for checks to finish 2026/02/05 02:05:06 DEBUG : Local file system at /tmp/rclone23648990/dir2: Waiting for transfers to finish 2026/02/05 02:05:06 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 02:05:06 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestServerSideMoveLocal (0.41s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 02:05:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 02:05:07 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/05 02:05:07 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:05:07 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:05:14 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/05 02:05:14 INFO : nested/sub dir/file: Copied (new) 2026/02/05 02:05:14 INFO : nested/sub dir/file: Deleted 2026/02/05 02:05:20 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 02:05:20 INFO : sub dir/hello world: Copied (new) 2026/02/05 02:05:20 INFO : sub dir/hello world: Deleted 2026/02/05 02:05:20 ERROR : Local file system at /tmp/rclone23648990: not deleting directories as there were IO errors sync_test.go:1819: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1819 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty run.go:130: removing dir "nested/sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "nested/sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "nested/sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "nested/sub dir" failed: directory not empty run.go:130: removing dir "nested" failed - try 1/3: directory not empty run.go:130: removing dir "nested" failed - try 2/3: directory not empty run.go:130: removing dir "nested" failed - try 3/3: directory not empty run.go:133: removing dir "nested" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1819 Error: Not equal: expected: []string{} actual : []string{"nested", "nested/sub dir", "sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,5 @@ -([]string) { +([]string) (len=3) { + (string) (len=6) "nested", + (string) (len=14) "nested/sub dir", + (string) (len=7) "sub dir" } Test: TestMoveWithDeleteEmptySrcDirs Messages: directories --- FAIL: TestMoveWithDeleteEmptySrcDirs (36.45s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 02:05:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/05 02:05:44 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/05 02:05:44 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:05:44 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:05:46 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/05 02:05:46 INFO : nested/sub dir/file: Copied (new) 2026/02/05 02:05:46 INFO : nested/sub dir/file: Deleted 2026/02/05 02:05:55 DEBUG : sub dir/hello world: size = 11 OK 2026/02/05 02:05:55 INFO : sub dir/hello world: Copied (new) 2026/02/05 02:05:55 INFO : sub dir/hello world: Deleted run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty 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 (35.75s) === RUN TestMoveWithIgnoreExisting run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 02:06:19 DEBUG : existing-b: Need to transfer - File not found at Destination 2026/02/05 02:06:19 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/05 02:06:19 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:06:19 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:06:22 DEBUG : existing-b: size = 6 OK 2026/02/05 02:06:22 INFO : existing-b: Copied (new) 2026/02/05 02:06:22 INFO : existing-b: Deleted 2026/02/05 02:06:23 DEBUG : existing: size = 6 OK 2026/02/05 02:06:23 INFO : existing: Copied (new) 2026/02/05 02:06:23 INFO : existing: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1873 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got fstest.go:203: Not found "existing" fstest.go:203: Not found "existing-b" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1873 Error: Not equal: expected: 0 actual : 2 Test: TestMoveWithIgnoreExisting Messages: 2 objects not found fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1873 Error: Not equal: expected: []string{} actual : []string{"nested", "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/05 02:06:33 DEBUG : existing: Destination exists, skipping 2026/02/05 02:06:33 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2026/02/05 02:06:34 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for checks to finish 2026/02/05 02:06:34 DEBUG : drime root 'rclone-test-dohugah5vuru': Waiting for transfers to finish 2026/02/05 02:06:34 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 2026/02/05 02:06:52 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:06:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 02:06:52 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:06:52 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 02:06:53 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 02:06:53 DEBUG : pacer: Reducing sleep to 10ms --- FAIL: TestMoveWithIgnoreExisting (38.96s) === RUN TestServerSideMove run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 02:06:58 DEBUG : Creating backend with remote "TestDrime:rclone-test-bobivap2yore" 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:1912 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestServerSideMove 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:1912 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMove Messages: 1 objects not found sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-dohugah5vuru' -> drime root 'rclone-test-bobivap2yore' 2026/02/05 02:07:20 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:07:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 02:07:20 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 02:07:31 DEBUG : drime root 'rclone-test-bobivap2yore': Using server-side directory move 2026/02/05 02:07:31 INFO : drime root 'rclone-test-bobivap2yore': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/05 02:07:32 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 02:07:32 DEBUG : empty space: size = 1 OK 2026/02/05 02:07:32 DEBUG : empty space: Sizes identical 2026/02/05 02:07:32 DEBUG : empty space: Unchanged skipping 2026/02/05 02:07:32 DEBUG : drime root 'rclone-test-bobivap2yore': Waiting for checks to finish 2026/02/05 02:07:32 DEBUG : potato3: size = 68 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 02:07:32 DEBUG : potato3: size = 60 (drime root 'rclone-test-bobivap2yore') 2026/02/05 02:07:32 DEBUG : potato3: Sizes differ 2026/02/05 02:07:32 INFO : empty space: Deleted 2026/02/05 02:07:32 DEBUG : drime root 'rclone-test-bobivap2yore': Waiting for transfers to finish 2026/02/05 02:07:33 INFO : potato3: Deleted 2026/02/05 02:07:33 ERROR : potato2: Couldn't move: object not found 2026/02/05 02:07:33 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/05 02:07:33 DEBUG : drime root 'rclone-test-bobivap2yore': Purge remote 2026/02/05 02:07:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:07:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 02:07:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:07:35 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 02:07:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:07:35 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 02:07:36 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:07:36 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 02:07:36 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:07:36 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 02:07:37 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:07:37 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 02:07:37 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:07:37 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 02:07:38 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:07:38 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 02:07:39 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:07:39 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 02:07:41 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 02:07:41 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/05 02:07:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:07:46 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 02:07:57 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:08:17 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:08:37 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:08:57 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:09:17 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:09:37 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:09:57 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:10:17 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:10:37 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/05 02:10:57 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:11:17 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:11:37 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:11:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:12:17 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:12:37 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:12:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:13:17 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:13:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:13:57 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/05 02:14:17 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:14:37 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:14:57 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:15:17 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:15:37 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:15:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:16:17 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:16:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:17:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:17:17 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/05 02:17:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:17:57 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:18:17 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:18:37 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:18:57 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:19:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:19:37 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:19:57 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:20:17 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:20:37 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/05 02:20:57 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:21:18 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:21:37 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:21:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:22:17 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:22:37 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:22:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:23:17 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:23:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:23:58 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/05 02:24:17 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:24:37 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:24:58 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:25:18 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:25:37 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:25:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:26:18 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:26:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:26:57 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:27:18 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/05 02:27:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:27:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:28:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:28:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:28:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:29:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:29:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:29:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:30:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:30:38 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/05 02:30:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:31:18 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:31:38 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:31:58 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:32:18 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:32:38 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:32:58 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:33:18 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:33:38 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:33:58 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/05 02:34:18 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestServerSideMove (1640.25s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 02:34:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-degamog2zoya" 2026/02/05 02:34:18 DEBUG : Config file has changed externally - reloading 2026/02/05 02:34:38 DEBUG : pacer: Reducing sleep to 5s 2026/02/05 02:34:51 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/05 02:34:53 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/05 02:35:07 DEBUG : pacer: Reducing sleep to 625ms 2026/02/05 02:35:07 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/05 02:35:11 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/05 02:35:11 DEBUG : pacer: Reducing sleep to 78.125ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/05 02:35:13 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/05 02:35:13 DEBUG : pacer: Reducing sleep to 19.53125ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/05 02:35:16 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go: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-dohugah5vuru' -> drime root 'rclone-test-degamog2zoya' 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/05 02:35:38 DEBUG : empty space: Excluded (Size Filter) 2026/02/05 02:35:38 DEBUG : empty space: Excluded 2026/02/05 02:35:38 DEBUG : empty space: Excluded (Size Filter) 2026/02/05 02:35:38 DEBUG : empty space: Excluded 2026/02/05 02:35:38 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 02:35:38 DEBUG : potato3: size = 68 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 02:35:38 DEBUG : potato3: size = 60 (drime root 'rclone-test-degamog2zoya') 2026/02/05 02:35:38 DEBUG : potato3: Sizes differ 2026/02/05 02:35:38 DEBUG : drime root 'rclone-test-degamog2zoya': Waiting for checks to finish 2026/02/05 02:35:38 DEBUG : drime root 'rclone-test-degamog2zoya': Waiting for transfers to finish 2026/02/05 02:35:39 INFO : potato3: Deleted 2026/02/05 02:35:39 ERROR : potato2: Couldn't move: object not found 2026/02/05 02:35:39 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/05 02:35:39 DEBUG : drime root 'rclone-test-degamog2zoya': Purge remote 2026/02/05 02:35:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:35:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 02:35:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:35:41 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 02:35:41 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:35:41 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 02:35:41 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:35:41 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 02:35:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:35:41 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 02:35:41 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:35:41 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 02:35:42 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:35:42 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 02:35:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:35:42 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 02:35:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:35:44 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 02:35:46 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 02:35:46 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/05 02:35:51 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:35:51 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 02:36:02 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:36:22 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:36:42 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:37:02 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:37:22 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:37:42 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:38:02 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:38:22 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:38:41 DEBUG : pacer: low level retry 10/10 (error Post "https://app.drime.cloud/api/v1/file-entries/delete": read tcp [2a01:4f9:c011:405e::1]:42154->[2606:4700::6812:db0]:443: read: connection reset by peer) run.go:130: removing file "potato2" failed - try 2/3: failed to delete item: Post "https://app.drime.cloud/api/v1/file-entries/delete": read tcp [2a01:4f9:c011:405e::1]:42154->[2606:4700::6812:db0]:443: read: connection reset by peer 2026/02/05 02:39:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:39:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:39:42 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:40:02 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:40:22 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:40:42 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:41:02 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:41:22 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:41:42 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:42:02 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/05 02:42:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:42:42 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:43:02 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:43:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:43:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:44:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:44:22 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:44:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:45:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:45:22 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/05 02:45:42 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:46:02 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:46:22 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:46:42 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:47:02 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:47:22 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:47:42 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:48:02 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:48:22 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:48:42 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/05 02:49:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:49:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:49:42 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:50:02 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:50:22 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:50:42 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:51:02 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:51:22 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:51:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:52:02 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/05 02:52:22 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestServerSideMoveWithFilter (1084.49s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "drime root 'rclone-test-dohugah5vuru'", Local "Local file system at /tmp/rclone23648990", Modify Window "876000h0m0s" 2026/02/05 02:52:22 DEBUG : Creating backend with remote "TestDrime:rclone-test-yodugin9lohu" 2026/02/05 02:52:22 DEBUG : Config file has changed externally - reloading 2026/02/05 02:52:42 DEBUG : pacer: Reducing sleep to 5s 2026/02/05 02:52:54 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/05 02:52:58 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/05 02:53:03 DEBUG : pacer: Reducing sleep to 625ms 2026/02/05 02:53:03 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/05 02:53:05 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/05 02:53:05 INFO : tomatoDir: Making directory 2026/02/05 02:53:05 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/05 02:53:06 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/05 02:53:06 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/05 02:53:08 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 6s just to make sure sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-dohugah5vuru' -> drime root 'rclone-test-yodugin9lohu' 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/05 02:53:31 DEBUG : drime root 'rclone-test-yodugin9lohu': Using server-side directory move 2026/02/05 02:53:31 INFO : drime root 'rclone-test-yodugin9lohu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/05 02:53:32 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/05 02:53:32 DEBUG : empty space: size = 1 OK 2026/02/05 02:53:32 DEBUG : empty space: Sizes identical 2026/02/05 02:53:32 DEBUG : empty space: Unchanged skipping 2026/02/05 02:53:32 DEBUG : potato3: size = 68 (drime root 'rclone-test-dohugah5vuru') 2026/02/05 02:53:32 DEBUG : potato3: size = 60 (drime root 'rclone-test-yodugin9lohu') 2026/02/05 02:53:32 DEBUG : potato3: Sizes differ 2026/02/05 02:53:32 INFO : empty space: Deleted 2026/02/05 02:53:32 INFO : potato3: Deleted 2026/02/05 02:53:32 ERROR : potato2: Couldn't move: object not found 2026/02/05 02:53:32 DEBUG : drime root 'rclone-test-yodugin9lohu': Waiting for checks to finish 2026/02/05 02:53:32 DEBUG : drime root 'rclone-test-yodugin9lohu': Waiting for transfers to finish 2026/02/05 02:53:33 ERROR : potato3: Couldn't move: object not found 2026/02/05 02:53:33 ERROR : drime root 'rclone-test-dohugah5vuru': 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/05 02:53:33 DEBUG : drime root 'rclone-test-yodugin9lohu': Purge remote 2026/02/05 02:53:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:53:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 02:53:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:53:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 02:53:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:53:34 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 02:53:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:53:34 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 02:53:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:53:35 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 02:53:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:53:35 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 02:53:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:53:35 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 02:53:36 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:53:36 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 02:53:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:53:37 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 02:53:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 02:53:40 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/05 02:53:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:53:45 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 02:53:55 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:54:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:54:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:54:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:55:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:55:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:55:55 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:56:15 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:56: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/05 02:56:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 02:57:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 02:57:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 02:57:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 02:58:16 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 02:58:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 02:58:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 02:59:16 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 02:59:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 02:59:56 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/05 03:00:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 03:00:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 03:00:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 03:01:15 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 03:01:36 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 03:01:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 03:02:16 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 03:02:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 03:02:56 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 03:03: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/05 03:03:36 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 03:03:56 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 03:04:16 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 03:04:36 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 03:04:56 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 03:05:16 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 03:05:36 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 03:05:56 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 03:06:16 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 03:06:36 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/05 03:06:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 03:07:16 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 03:07:36 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 03:07:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 03:08:16 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 03:08:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 03:08:56 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 03:09:16 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 03:09:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 03:09:56 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" panic: test timed out after 2h0m0s running tests: TestServerSideMoveDeleteEmptySourceDirs (17m37s) goroutine 11488 [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(0xc000a26540, {0x29a88e0?, 0xc000411ad8?}, 0x2ab0ce0) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc000a26540) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc000a26540, 0xc000411c18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc0006c9ec0, {0x44671e0, 0x6c, 0x6c}, {0x84a160?, 0x44a31f0?, 0x447f440?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc000808140) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc000808140) /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 11334 [chan receive]: github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall(0xc0000afc80, 0x0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:162 +0x26 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0000afc80, 0xc00085e040, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:215 +0xb2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0000afc80, 0xc00085e040) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:235 +0x93 github.com/rclone/rclone/backend/drime.(*Fs).deleteObject(0xc0008081e0, {0x2e502b8, 0x44a2c60}, {0xc000466e77, 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(0xc0008bc380, {0xc00085c108, 0x17}, 0xc000867d28) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:126 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc00085e4c0?, 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?}, {0xc00085e4c0, 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 11189 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11331 [select, 16 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 11189 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 4076 [chan receive, 102 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 4089 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 11330 [select, 16 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 11189 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11263 [select, 16 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 11189 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11332 [select, 16 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 11189 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11189 [sync.WaitGroup.Wait, 16 minutes]: sync.runtime_SemacquireWaitGroup(0xc00076f7d0?) /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, 0xc0008081e0}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc00026ccf0, 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, 0xc0008081e0}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc00026ccf0) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:76 +0x2a5 github.com/rclone/rclone/fs/walk.listRwalk({0x2e502b8, 0x44a2c60}, {0x2e67310, 0xc0008081e0}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc00026ccc0) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:170 +0x10d github.com/rclone/rclone/fs/walk.ListR({0x2e502b8, 0x44a2c60}, {0x2e67310, 0xc0008081e0}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc00026ccc0) /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(0xc00066e7e0) /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(0xc0008bc380, 0xc000a5e540?) /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(0xc0008bc380) /usr/local/go/src/testing/testing.go:1041 +0x4a github.com/stretchr/testify/require.NoError({0x2e3a190, 0xc0008bc380}, {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}, 0xc0008bc380, 0xc00066e7e0, 0x0, 0x1) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747 +0x827 github.com/rclone/rclone/fs/sync.TestServerSideMoveDeleteEmptySourceDirs(0xc0008bc380) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1932 +0x48 testing.tRunner(0xc0008bc380, 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 10830 [IO wait]: internal/poll.runtime_pollWait(0x71018d2786f0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0007e6280?, 0xc0004fc600?, 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(0xc0007e6280, {0xc0004fc600, 0x1300, 0x1300}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0007e6280, {0xc0004fc600?, 0x1265?, 0xc0004b82a0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00015c000, {0xc0004fc600?, 0xc0004fc696?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000928000, {0xc0004fc600?, 0x71014471f5c8?, 0x71018d61da78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000444090, {0xc0004fc600?, 0x1265?, 0x2?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc00013c2b8, {0x2e29360, 0xc000444090}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc00013c008, {0x710144600f58, 0xc000928000}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc00013c008, 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(0xc00013c008, {0xc000834000, 0x1000, 0xc0003f6ca0?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 bufio.(*Reader).Read(0xc00094b440, {0xc00040a040, 0x9, 0x43e8760?}) /usr/local/go/src/bufio/bufio.go:245 +0x197 io.ReadAtLeast({0x2e263c0, 0xc00094b440}, {0xc00040a040, 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({0xc00040a040, 0x9, 0xc0001c2060?}, {0x2e263c0?, 0xc00094b440?}) /usr/local/go/src/net/http/h2_bundle.go:1805 +0x65 net/http.(*http2Framer).ReadFrame(0xc00040a000) /usr/local/go/src/net/http/h2_bundle.go:2072 +0x7d net/http.(*http2clientConnReadLoop).run(0xc0003f6fa8) /usr/local/go/src/net/http/h2_bundle.go:9933 +0xda net/http.(*http2ClientConn).readLoop(0xc000a61180) /usr/local/go/src/net/http/h2_bundle.go:9812 +0x79 created by net/http.(*http2Transport).newClientConn in goroutine 10829 /usr/local/go/src/net/http/h2_bundle.go:8334 +0xde5 goroutine 11264 [select, 16 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 11189 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11265 [select, 16 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 11189 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11333 [select, 16 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 11189 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1df goroutine 11359 [sleep]: time.Sleep(0x4a817c800) /usr/local/go/src/runtime/time.go:338 +0x165 github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000c0af60?) /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 11334 /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.177766708s (try 1/5): exit status 2: Failed [TestCopy TestCopyMetadata TestCopyNoTraverse TestCopyNoTraverseDeadlock TestCopyCheckFirst TestSyncNoTraverse TestCopyWithFilesFromAndNoTraverse TestCopyEmptyDirectories TestCopyNoEmptyDirectories TestMoveEmptyDirectories TestMoveNoEmptyDirectories TestSyncEmptyDirectories TestSyncSetDelayedModTimes TestSyncNoEmptyDirectories TestServerSideCopy TestCopyOverSelf TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf TestCopyAfterDelete TestCopyRedownload TestSyncSizeOnly TestSyncIgnoreSize TestSyncIgnoreTimes TestSyncIgnoreExisting TestSyncIgnoreErrors TestSyncAfterAddingAFile TestSyncAfterRemovingAFileAndAddingAFileDryRun TestSyncAfterRemovingAFileAndAddingAFile TestSyncAfterRemovingAFileAndAddingAFileSubDir TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors TestSyncDeleteBefore TestSyncWithExclude TestSyncWithExcludeAndDeleteExcluded TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestMoveWithoutDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMove TestServerSideMoveWithFilter]