"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCopyAfterDelete|TestCopyNoTraverse|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBasedOnCheckSum|TestSyncEmptyDirectories|TestSyncIgnoreErrors|TestSyncIgnoreExisting|TestSyncIgnoreTimes|TestSyncWithTrackRenamesStrategyLeaf)$'" - Starting (try 4/5) 2026/02/06 08:20:12 DEBUG : Creating backend with remote "TestDrime:rclone-test-ziquqal4jato" 2026/02/06 08:20:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:20:20 DEBUG : Creating backend with remote "/tmp/rclone1580300482" === RUN TestCopyNoTraverse run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:20:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/06 08:20:24 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:20:24 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:21:02 DEBUG : sub dir/hello world: size = 11 OK 2026/02/06 08:21:02 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (69.76s) === RUN TestMoveEmptyDirectories run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:21:30 DEBUG : sub dir2: Making directory with metadata 2026/02/06 08:21:30 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/06 08:21:33 INFO : sub dir2: Making directory 2026/02/06 08:21:39 INFO : sub dir: Making directory 2026/02/06 08:21:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/06 08:21:45 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:21:45 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:22:01 DEBUG : sub dir/hello world: size = 11 OK 2026/02/06 08:22:01 INFO : sub dir/hello world: Copied (new) 2026/02/06 08:22:01 INFO : sub dir/hello world: Deleted 2026/02/06 08:22:06 DEBUG : drime root 'rclone-test-ziquqal4jato': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2026/02/06 08:22:09 DEBUG : drime root 'rclone-test-ziquqal4jato': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (60.64s) === RUN TestMoveNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:22:30 INFO : sub dir2: Making directory 2026/02/06 08:22:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/06 08:22:33 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:22:33 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:22:51 DEBUG : sub dir/hello world: size = 11 OK 2026/02/06 08:22:51 INFO : sub dir/hello world: Copied (new) 2026/02/06 08:22:51 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (45.58s) === RUN TestSyncEmptyDirectories run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:23:16 DEBUG : sub dir2: Making directory with metadata 2026/02/06 08:23:16 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/06 08:23:16 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/06 08:23:20 INFO : sub dir2: Making directory 2026/02/06 08:23:26 INFO : sub dir: Making directory 2026/02/06 08:23:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/06 08:23:31 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:23:31 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:23:41 DEBUG : sub dir/hello world: size = 11 OK 2026/02/06 08:23:41 INFO : sub dir/hello world: Copied (new) 2026/02/06 08:23:41 DEBUG : Waiting for deletions to finish 2026/02/06 08:23:47 DEBUG : drime root 'rclone-test-ziquqal4jato': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (71.37s) === RUN TestServerSideCopy run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:25:15 DEBUG : Creating backend with remote "TestDrime:rclone-test-boxamuf6doya" sync_test.go:620: Server side copy (if possible) drime root 'rclone-test-ziquqal4jato' -> drime root 'rclone-test-boxamuf6doya' 2026/02/06 08:25:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/06 08:25:40 DEBUG : drime root 'rclone-test-boxamuf6doya': Waiting for checks to finish 2026/02/06 08:25:40 DEBUG : drime root 'rclone-test-boxamuf6doya': Waiting for transfers to finish 2026/02/06 08:26:17 DEBUG : sub dir/hello world: size = 11 OK 2026/02/06 08:26:17 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/06 08:26:28 DEBUG : drime root 'rclone-test-boxamuf6doya': Purge remote --- PASS: TestServerSideCopy (161.87s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:27:44 DEBUG : Creating backend with remote "TestDrime:rclone-test-guxafoy9fude" sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-ziquqal4jato' -> drime root 'rclone-test-guxafoy9fude' 2026/02/06 08:28:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/06 08:28:00 DEBUG : drime root 'rclone-test-guxafoy9fude': Waiting for checks to finish 2026/02/06 08:28:00 DEBUG : drime root 'rclone-test-guxafoy9fude': Waiting for transfers to finish 2026/02/06 08:28:20 DEBUG : sub dir/hello world: size = 11 OK 2026/02/06 08:28:20 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/06 08:28:36 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/06 08:28:45 DEBUG : drime root 'rclone-test-guxafoy9fude': Waiting for checks to finish 2026/02/06 08:28:45 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-ziquqal4jato') 2026/02/06 08:28:45 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-guxafoy9fude') 2026/02/06 08:28:45 DEBUG : sub dir/hello world: Sizes differ 2026/02/06 08:28:45 DEBUG : drime root 'rclone-test-guxafoy9fude': Waiting for transfers to finish 2026/02/06 08:28:48 DEBUG : sub dir/hello world: Server side copy: removing existing object after successful copy 2026/02/06 08:28:49 DEBUG : sub dir/hello world: size = 17 OK 2026/02/06 08:28:49 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:292: Sleeping for 6s just to make sure 2026/02/06 08:29:04 DEBUG : drime root 'rclone-test-guxafoy9fude': Purge remote run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestServerSideCopyOverSelf Messages: directories --- FAIL: TestServerSideCopyOverSelf (132.08s) === RUN TestMoveOverSelf run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:29:32 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone1580300482) 2026/02/06 08:29:32 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-ziquqal4jato') 2026/02/06 08:29:32 DEBUG : sub dir/hello world: Sizes differ 2026/02/06 08:29:32 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:29:32 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:29:36 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/06 08:29:36 DEBUG : sub dir/hello world: size = 17 OK 2026/02/06 08:29:36 INFO : sub dir/hello world: Copied (replaced existing) 2026/02/06 08:29:36 INFO : sub dir/hello world: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty --- PASS: TestMoveOverSelf (31.84s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", 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/06 08:30:12 DEBUG : Creating backend with remote "TestDrime:rclone-test-casilod2mixi" sync_test.go:701: Server side copy (if possible) drime root 'rclone-test-ziquqal4jato' -> drime root 'rclone-test-casilod2mixi' 2026/02/06 08:30:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/06 08:30:13 DEBUG : drime root 'rclone-test-casilod2mixi': Waiting for checks to finish 2026/02/06 08:30:13 DEBUG : drime root 'rclone-test-casilod2mixi': Waiting for transfers to finish 2026/02/06 08:30:16 DEBUG : sub dir/hello world: size = 11 OK 2026/02/06 08:30:16 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/06 08:30:21 DEBUG : sub dir/hello world: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:710 Error: Should be true Test: TestServerSideMoveOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (11) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:710 Error: Not equal: expected: 17 actual : 11 Test: TestServerSideMoveOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 2026/02/06 08:30:31 DEBUG : drime root 'rclone-test-casilod2mixi': Using server-side directory move 2026/02/06 08:30:31 INFO : drime root 'rclone-test-casilod2mixi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/06 08:30:32 DEBUG : drime root 'rclone-test-casilod2mixi': Waiting for checks to finish 2026/02/06 08:30:32 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-ziquqal4jato') 2026/02/06 08:30:32 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-casilod2mixi') 2026/02/06 08:30:32 DEBUG : sub dir/hello world: Sizes differ 2026/02/06 08:30:32 DEBUG : drime root 'rclone-test-casilod2mixi': Waiting for transfers to finish 2026/02/06 08:30:32 INFO : sub dir/hello world: Deleted 2026/02/06 08:30:33 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/06 08:30:33 DEBUG : drime root 'rclone-test-casilod2mixi': Purge remote 2026/02/06 08:30:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 08:30:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 08:30:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 08:30:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 08:30:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 08:30:35 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 08:30:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 08:30:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 08:30:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 08:30:35 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 08:30:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 08:30:36 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 08:30:36 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 08:30:36 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 08:30:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 08:30:37 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 08:30:38 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 08:30:38 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 08:30:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 08:30:40 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/06 08:30:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 08:30:46 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 08:30:56 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 08:31:17 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 08:31:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 08:31:56 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 08:32:16 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 08:32:36 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 08:32:56 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 08:33:16 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 08:33:36 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/06 08:33:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 08:34:16 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 08:34:36 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 08:34:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 08:35:16 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 08:35:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 08:35:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 08:36:17 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 08:36:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 08:36:56 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/06 08:37:17 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 08:37:39 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 08:37:48 DEBUG : pacer: Reducing sleep to 2.5s --- FAIL: TestServerSideMoveOverSelf (475.00s) === RUN TestCopyAfterDelete run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:37:54 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/06 08:37:56 DEBUG : pacer: Reducing sleep to 625ms 2026/02/06 08:38:00 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 08:38:23 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/06 08:38:23 ERROR : error listing: directory not found 2026/02/06 08:38:26 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/06 08:38:29 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/06 08:38:29 INFO : Local file system at /tmp/rclone1580300482: Making directory 2026/02/06 08:38:31 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 08:38:34 DEBUG : pacer: Reducing sleep to 10ms 2026/02/06 08:38:34 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:38:34 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (73.67s) === RUN TestSyncBasedOnCheckSum run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:39:05 DEBUG : check sum: Need to transfer - File not found at Destination 2026/02/06 08:39:05 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:39:05 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:39:14 DEBUG : check sum: size = 1 OK 2026/02/06 08:39:14 INFO : check sum: Copied (new) 2026/02/06 08:39:14 DEBUG : Waiting for deletions to finish 2026/02/06 08:39:19 DEBUG : check sum: size = 1 OK 2026/02/06 08:39:19 NOTICE: drime root 'rclone-test-ziquqal4jato': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/02/06 08:39:19 DEBUG : check sum: Size of src and dst objects identical 2026/02/06 08:39:19 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:39:19 DEBUG : check sum: Unchanged skipping 2026/02/06 08:39:19 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:39:19 DEBUG : Waiting for deletions to finish 2026/02/06 08:39:19 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestSyncBasedOnCheckSum (46.69s) === RUN TestSyncIgnoreTimes run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:40:09 DEBUG : existing: size = 6 OK 2026/02/06 08:40:09 DEBUG : existing: Sizes identical 2026/02/06 08:40:09 DEBUG : existing: Unchanged skipping 2026/02/06 08:40:09 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:40:09 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:40:09 DEBUG : Waiting for deletions to finish 2026/02/06 08:40:09 INFO : There was nothing to transfer 2026/02/06 08:40:12 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2026/02/06 08:40:12 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:40:12 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:40:28 DEBUG : existing: Removing old object on successful upload 2026/02/06 08:40:32 DEBUG : existing: size = 6 OK 2026/02/06 08:40:32 INFO : existing: Copied (replaced existing) 2026/02/06 08:40:32 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (59.12s) === RUN TestSyncIgnoreExisting run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:40:51 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/06 08:40:51 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:40:51 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:41:00 DEBUG : existing: size = 6 OK 2026/02/06 08:41:00 INFO : existing: Copied (new) 2026/02/06 08:41:00 DEBUG : Waiting for deletions to finish 2026/02/06 08:41:07 DEBUG : existing: Destination exists, skipping 2026/02/06 08:41:07 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:41:07 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:41:07 DEBUG : Waiting for deletions to finish 2026/02/06 08:41:07 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (33.03s) === RUN TestSyncIgnoreErrors run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:41:53 INFO : d: Making directory 2026/02/06 08:42:11 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/06 08:42:15 DEBUG : c/non empty space: size = 5 OK 2026/02/06 08:42:15 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:42:15 DEBUG : c/non empty space: Sizes identical 2026/02/06 08:42:15 DEBUG : c/non empty space: Unchanged skipping 2026/02/06 08:42:15 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:42:26 DEBUG : a/potato2: size = 60 OK 2026/02/06 08:42:26 INFO : a/potato2: Copied (new) 2026/02/06 08:42:26 DEBUG : Waiting for deletions to finish 2026/02/06 08:42:30 INFO : b/potato: Deleted 2026/02/06 08:42:30 INFO : d: Removing directory 2026/02/06 08:42:35 INFO : b: Removing directory 2026/02/06 08:42:42 DEBUG : drime root 'rclone-test-ziquqal4jato': deleted 2 directories --- PASS: TestSyncIgnoreErrors (136.24s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" sync_test.go:1680: Can track renames: false 2026/02/06 08:43:42 INFO : drime root 'rclone-test-ziquqal4jato': Making map for --track-renames 2026/02/06 08:43:42 INFO : drime root 'rclone-test-ziquqal4jato': Finished making map for --track-renames 2026/02/06 08:43:42 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:43:42 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/02/06 08:43:42 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2026/02/06 08:43:42 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for renames to finish 2026/02/06 08:43:42 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:44:07 DEBUG : sub/yam: size = 11 OK 2026/02/06 08:44:07 INFO : sub/yam: Copied (new) 2026/02/06 08:44:13 DEBUG : potato: size = 14 OK 2026/02/06 08:44:13 INFO : potato: Copied (new) 2026/02/06 08:44:13 DEBUG : Waiting for deletions to finish 2026/02/06 08:44:23 DEBUG : potato: size = 14 OK 2026/02/06 08:44:23 DEBUG : potato: Sizes identical 2026/02/06 08:44:23 DEBUG : potato: Unchanged skipping 2026/02/06 08:44:25 INFO : drime root 'rclone-test-ziquqal4jato': Making map for --track-renames 2026/02/06 08:44:25 INFO : drime root 'rclone-test-ziquqal4jato': Finished making map for --track-renames 2026/02/06 08:44:25 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:44:25 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for renames to finish 2026/02/06 08:44:37 ERROR : sub/yam: Couldn't move: object not found 2026/02/06 08:44:37 DEBUG : yam: Failed to rename to "sub/yam": object not found 2026/02/06 08:44:37 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/02/06 08:44:37 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:44:50 DEBUG : yam: Removing old object on successful upload 2026/02/06 08:44:53 DEBUG : yam: size = 11 OK 2026/02/06 08:44:53 INFO : yam: Copied (new) 2026/02/06 08:44:53 ERROR : drime root 'rclone-test-ziquqal4jato': not deleting files as there were IO errors 2026/02/06 08:44:53 ERROR : drime root 'rclone-test-ziquqal4jato': not deleting directories as there were IO errors sync_test.go:1698: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1698 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (100.48s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:45:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/06 08:45:21 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/06 08:45:21 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for checks to finish 2026/02/06 08:45:21 DEBUG : drime root 'rclone-test-ziquqal4jato': Waiting for transfers to finish 2026/02/06 08:46:04 DEBUG : sub dir/hello world: size = 11 OK 2026/02/06 08:46:04 INFO : sub dir/hello world: Copied (new) 2026/02/06 08:46:04 INFO : sub dir/hello world: Deleted 2026/02/06 08:46:06 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/06 08:46:06 INFO : nested/sub dir/file: Copied (new) 2026/02/06 08:46:06 INFO : nested/sub dir/file: Deleted 2026/02/06 08:46:06 ERROR : Local file system at /tmp/rclone1580300482: not deleting directories as there were IO errors sync_test.go:1819: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1819 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (90.39s) === RUN TestServerSideMove run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:46:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-kegisog2zewo" sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-ziquqal4jato' -> drime root 'rclone-test-kegisog2zewo' 2026/02/06 08:48:22 DEBUG : drime root 'rclone-test-kegisog2zewo': Using server-side directory move 2026/02/06 08:48:22 INFO : drime root 'rclone-test-kegisog2zewo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/06 08:48:27 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/06 08:48:27 DEBUG : empty space: size = 1 OK 2026/02/06 08:48:27 DEBUG : empty space: Sizes identical 2026/02/06 08:48:27 DEBUG : empty space: Unchanged skipping 2026/02/06 08:48:27 DEBUG : potato3: size = 68 (drime root 'rclone-test-ziquqal4jato') 2026/02/06 08:48:27 DEBUG : potato3: size = 60 (drime root 'rclone-test-kegisog2zewo') 2026/02/06 08:48:27 DEBUG : potato3: Sizes differ 2026/02/06 08:48:27 DEBUG : drime root 'rclone-test-kegisog2zewo': Waiting for checks to finish 2026/02/06 08:48:32 INFO : potato3: Deleted 2026/02/06 08:48:32 INFO : empty space: Deleted 2026/02/06 08:48:32 DEBUG : drime root 'rclone-test-kegisog2zewo': Waiting for transfers to finish 2026/02/06 08:48:38 INFO : potato2: Moved (server-side) 2026/02/06 08:48:42 INFO : potato3: Moved (server-side) 2026/02/06 08:48:51 DEBUG : Creating backend with remote "TestDrime:rclone-test-liyujeq7zofi" 2026/02/06 08:49:01 DEBUG : drime root 'rclone-test-liyujeq7zofi': Using server-side directory move 2026/02/06 08:49:10 INFO : drime root 'rclone-test-liyujeq7zofi': Server side directory move succeeded 2026/02/06 08:49:24 ERROR : error listing: directory not found 2026/02/06 08:49:24 DEBUG : drime root 'rclone-test-liyujeq7zofi': Purge remote 2026/02/06 08:49:29 DEBUG : drime root 'rclone-test-kegisog2zewo': Purge remote 2026/02/06 08:49:33 NOTICE: purge failed: directory not found --- PASS: TestServerSideMove (173.99s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "drime root 'rclone-test-ziquqal4jato'", Local "Local file system at /tmp/rclone1580300482", Modify Window "876000h0m0s" 2026/02/06 08:49:42 DEBUG : Creating backend with remote "TestDrime:rclone-test-vetoziv6suyu" sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-ziquqal4jato' -> drime root 'rclone-test-vetoziv6suyu' 2026/02/06 08:51:55 DEBUG : empty space: Excluded (Size Filter) 2026/02/06 08:51:55 DEBUG : empty space: Excluded 2026/02/06 08:51:56 DEBUG : empty space: Excluded (Size Filter) 2026/02/06 08:51:56 DEBUG : empty space: Excluded 2026/02/06 08:51:56 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/06 08:51:56 DEBUG : potato3: size = 68 (drime root 'rclone-test-ziquqal4jato') 2026/02/06 08:51:56 DEBUG : potato3: size = 60 (drime root 'rclone-test-vetoziv6suyu') 2026/02/06 08:51:56 DEBUG : potato3: Sizes differ 2026/02/06 08:51:56 DEBUG : drime root 'rclone-test-vetoziv6suyu': Waiting for checks to finish 2026/02/06 08:51:56 DEBUG : drime root 'rclone-test-vetoziv6suyu': Waiting for transfers to finish 2026/02/06 08:51:59 INFO : potato3: Deleted 2026/02/06 08:52:05 INFO : potato2: Moved (server-side) 2026/02/06 08:52:10 INFO : potato3: Moved (server-side) 2026/02/06 08:52:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-copovez7ralo" 2026/02/06 08:52:32 DEBUG : empty space: Excluded (Size Filter) 2026/02/06 08:52:32 DEBUG : empty space: Excluded 2026/02/06 08:52:33 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/06 08:52:33 DEBUG : potato3: Need to transfer - File not found at Destination 2026/02/06 08:52:33 DEBUG : drime root 'rclone-test-copovez7ralo': Waiting for checks to finish 2026/02/06 08:52:33 DEBUG : drime root 'rclone-test-copovez7ralo': Waiting for transfers to finish 2026/02/06 08:52:49 INFO : potato3: Moved (server-side) 2026/02/06 08:52:49 INFO : potato2: Moved (server-side) 2026/02/06 08:52:58 DEBUG : drime root 'rclone-test-copovez7ralo': Purge remote 2026/02/06 08:53:03 DEBUG : drime root 'rclone-test-vetoziv6suyu': Purge remote 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: TestServerSideMoveWithFilter (233.51s) FAIL 2026/02/06 08:53:35 DEBUG : drime root 'rclone-test-ziquqal4jato': Purge remote "./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCopyAfterDelete|TestCopyNoTraverse|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBasedOnCheckSum|TestSyncEmptyDirectories|TestSyncIgnoreErrors|TestSyncIgnoreExisting|TestSyncIgnoreTimes|TestSyncWithTrackRenamesStrategyLeaf)$'" - Finished ERROR in 33m26.961141111s (try 4/5): exit status 1: Failed [TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs]