"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCopyOverSelf|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncBackupDir|TestSyncCompareDest|TestSyncCopyDest|TestSyncIgnoreTimes|TestSyncWithTrackRenamesStrategyLeaf)$'" - Starting (try 3/5) 2026/03/04 05:05:44 DEBUG : Creating backend with remote "TestDrime:rclone-test-gurebit6jasa" 2026/03/04 05:05:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/03/04 05:05:44 DEBUG : Creating backend with remote "/tmp/rclone3043630884" === RUN TestCopyOverSelf run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" 2026/03/04 05:05:47 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3043630884) 2026/03/04 05:05:47 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-gurebit6jasa') 2026/03/04 05:05:47 DEBUG : sub dir/hello world: Sizes differ 2026/03/04 05:05:47 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:05:47 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:05:47 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/03/04 05:05:48 ERROR : sub dir/hello world: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." sync_test.go:641: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:641 Error: Received unexpected error: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." Test: TestCopyOverSelf run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty --- FAIL: TestCopyOverSelf (6.20s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" 2026/03/04 05:05:53 DEBUG : Creating backend with remote "TestDrime:rclone-test-siwopar1zowo" sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-gurebit6jasa' -> drime root 'rclone-test-siwopar1zowo' 2026/03/04 05:05:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/03/04 05:05:54 DEBUG : drime root 'rclone-test-siwopar1zowo': Waiting for checks to finish 2026/03/04 05:05:54 DEBUG : drime root 'rclone-test-siwopar1zowo': Waiting for transfers to finish 2026/03/04 05:05:55 DEBUG : sub dir/hello world: size = 11 OK 2026/03/04 05:05:55 INFO : sub dir/hello world: Copied (server-side copy) 2026/03/04 05:05:57 DEBUG : sub dir/hello world: Removing old object on successful upload run.go:290: Failed to put "sub dir/hello world" to "drime root 'rclone-test-gurebit6jasa'": failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/03/04 05:05:57 DEBUG : drime root 'rclone-test-siwopar1zowo': Purge remote run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty --- FAIL: TestServerSideCopyOverSelf (9.09s) === RUN TestMoveOverSelf run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" 2026/03/04 05:06:03 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3043630884) 2026/03/04 05:06:03 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-gurebit6jasa') 2026/03/04 05:06:03 DEBUG : sub dir/hello world: Sizes differ 2026/03/04 05:06:03 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:06:03 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:06:04 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/03/04 05:06:04 ERROR : sub dir/hello world: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/03/04 05:06:04 ERROR : sub dir/hello world: Not deleting source as copy failed: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." sync_test.go:685: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685 Error: Received unexpected error: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." Test: TestMoveOverSelf run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty --- FAIL: TestMoveOverSelf (6.94s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" 2026/03/04 05:06:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-rovonez2foto" sync_test.go:701: Server side copy (if possible) drime root 'rclone-test-gurebit6jasa' -> drime root 'rclone-test-rovonez2foto' 2026/03/04 05:06:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/03/04 05:06:09 DEBUG : drime root 'rclone-test-rovonez2foto': Waiting for checks to finish 2026/03/04 05:06:09 DEBUG : drime root 'rclone-test-rovonez2foto': Waiting for transfers to finish 2026/03/04 05:06:11 DEBUG : sub dir/hello world: size = 11 OK 2026/03/04 05:06:11 INFO : sub dir/hello world: Copied (server-side copy) 2026/03/04 05:06:12 DEBUG : sub dir/hello world: Removing old object on successful upload run.go:290: Failed to put "sub dir/hello world" to "drime root 'rclone-test-gurebit6jasa'": failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/03/04 05:06:13 DEBUG : drime root 'rclone-test-rovonez2foto': Purge remote run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty --- FAIL: TestServerSideMoveOverSelf (9.71s) === RUN TestSyncIgnoreTimes run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", 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/03/04 05:06:25 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:06:25 DEBUG : existing: size = 6 OK 2026/03/04 05:06:25 DEBUG : existing: Sizes identical 2026/03/04 05:06:25 DEBUG : existing: Unchanged skipping 2026/03/04 05:06:25 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:06:25 DEBUG : Waiting for deletions to finish 2026/03/04 05:06:25 INFO : There was nothing to transfer 2026/03/04 05:06:25 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2026/03/04 05:06:25 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:06:25 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:06:26 DEBUG : existing: Removing old object on successful upload 2026/03/04 05:06:27 ERROR : existing: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/03/04 05:06:27 ERROR : drime root 'rclone-test-gurebit6jasa': not deleting files as there were IO errors 2026/03/04 05:06:27 ERROR : drime root 'rclone-test-gurebit6jasa': not deleting directories as there were IO errors sync_test.go:905: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:905 Error: Received unexpected error: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." Test: TestSyncIgnoreTimes 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: TestSyncIgnoreTimes (18.65s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", 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/03/04 05:06:44 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:06:44 DEBUG : potato: size = 21 (Local file system at /tmp/rclone3043630884) 2026/03/04 05:06:44 DEBUG : potato: size = 60 (drime root 'rclone-test-gurebit6jasa') 2026/03/04 05:06:44 DEBUG : potato: Sizes differ 2026/03/04 05:06:44 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:06:45 DEBUG : potato: Removing old object on successful upload 2026/03/04 05:06:45 ERROR : potato: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/03/04 05:06:45 ERROR : drime root 'rclone-test-gurebit6jasa': not deleting files as there were IO errors 2026/03/04 05:06:45 ERROR : drime root 'rclone-test-gurebit6jasa': not deleting directories as there were IO errors sync_test.go:1123: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1123 Error: Received unexpected error: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." Test: TestSyncAfterChangingFilesSizeOnly 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: TestSyncAfterChangingFilesSizeOnly (17.84s) === RUN TestSyncAfterChangingContentsOnly run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", 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/03/04 05:07:01 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:07:01 DEBUG : potato: size = 21 (Local file system at /tmp/rclone3043630884) 2026/03/04 05:07:01 DEBUG : potato: size = 36 (drime root 'rclone-test-gurebit6jasa') 2026/03/04 05:07:01 DEBUG : potato: Sizes differ 2026/03/04 05:07:01 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:07:02 DEBUG : potato: Removing old object on successful upload 2026/03/04 05:07:02 ERROR : potato: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/03/04 05:07:02 ERROR : drime root 'rclone-test-gurebit6jasa': not deleting files as there were IO errors 2026/03/04 05:07:02 ERROR : drime root 'rclone-test-gurebit6jasa': not deleting directories as there were IO errors sync_test.go:1148: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1148 Error: Received unexpected error: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." Test: TestSyncAfterChangingContentsOnly 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: TestSyncAfterChangingContentsOnly (17.50s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" sync_test.go:1680: Can track renames: false 2026/03/04 05:07:11 INFO : drime root 'rclone-test-gurebit6jasa': Making map for --track-renames 2026/03/04 05:07:11 INFO : drime root 'rclone-test-gurebit6jasa': Finished making map for --track-renames 2026/03/04 05:07:11 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:07:11 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for renames to finish 2026/03/04 05:07:11 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/03/04 05:07:11 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2026/03/04 05:07:11 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:07:12 DEBUG : potato: size = 14 OK 2026/03/04 05:07:12 INFO : potato: Copied (new) 2026/03/04 05:07:12 DEBUG : sub/yam: size = 11 OK 2026/03/04 05:07:12 INFO : sub/yam: Copied (new) 2026/03/04 05:07:12 DEBUG : Waiting for deletions to finish 2026/03/04 05:07:13 DEBUG : potato: size = 14 OK 2026/03/04 05:07:13 DEBUG : potato: Sizes identical 2026/03/04 05:07:13 DEBUG : potato: Unchanged skipping 2026/03/04 05:07:13 INFO : drime root 'rclone-test-gurebit6jasa': Making map for --track-renames 2026/03/04 05:07:13 INFO : drime root 'rclone-test-gurebit6jasa': Finished making map for --track-renames 2026/03/04 05:07:13 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:07:13 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for renames to finish 2026/03/04 05:07:13 ERROR : sub/yam: Couldn't move: object not found 2026/03/04 05:07:13 DEBUG : yam: Failed to rename to "sub/yam": object not found 2026/03/04 05:07:13 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/03/04 05:07:13 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:07:14 DEBUG : yam: size = 11 OK 2026/03/04 05:07:14 INFO : yam: Copied (new) 2026/03/04 05:07:14 ERROR : drime root 'rclone-test-gurebit6jasa': not deleting files as there were IO errors 2026/03/04 05:07:14 ERROR : drime root 'rclone-test-gurebit6jasa': 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 (5.37s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" 2026/03/04 05:07:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/03/04 05:07:16 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/03/04 05:07:16 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for checks to finish 2026/03/04 05:07:16 DEBUG : drime root 'rclone-test-gurebit6jasa': Waiting for transfers to finish 2026/03/04 05:07:19 DEBUG : nested/sub dir/file: size = 6 OK 2026/03/04 05:07:19 INFO : nested/sub dir/file: Copied (new) 2026/03/04 05:07:19 INFO : nested/sub dir/file: Deleted 2026/03/04 05:07:19 DEBUG : sub dir/hello world: size = 11 OK 2026/03/04 05:07:19 INFO : sub dir/hello world: Copied (new) 2026/03/04 05:07:19 INFO : sub dir/hello world: Deleted 2026/03/04 05:07:19 ERROR : Local file system at /tmp/rclone3043630884: 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 --- FAIL: TestMoveWithDeleteEmptySrcDirs (7.00s) === RUN TestServerSideMove run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" 2026/03/04 05:07:23 DEBUG : Creating backend with remote "TestDrime:rclone-test-fulobam6cixe" sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-gurebit6jasa' -> drime root 'rclone-test-fulobam6cixe' 2026/03/04 05:07:28 DEBUG : drime root 'rclone-test-fulobam6cixe': Using server-side directory move 2026/03/04 05:07:28 INFO : drime root 'rclone-test-fulobam6cixe': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/03/04 05:07:28 DEBUG : potato2: Need to transfer - File not found at Destination 2026/03/04 05:07:28 DEBUG : empty space: size = 1 OK 2026/03/04 05:07:28 DEBUG : empty space: Sizes identical 2026/03/04 05:07:28 DEBUG : empty space: Unchanged skipping 2026/03/04 05:07:28 DEBUG : drime root 'rclone-test-fulobam6cixe': Waiting for checks to finish 2026/03/04 05:07:28 DEBUG : potato3: size = 68 (drime root 'rclone-test-gurebit6jasa') 2026/03/04 05:07:28 DEBUG : potato3: size = 60 (drime root 'rclone-test-fulobam6cixe') 2026/03/04 05:07:28 DEBUG : potato3: Sizes differ 2026/03/04 05:07:28 INFO : empty space: Deleted 2026/03/04 05:07:28 DEBUG : drime root 'rclone-test-fulobam6cixe': Waiting for transfers to finish 2026/03/04 05:07:28 INFO : potato3: Deleted 2026/03/04 05:07:28 ERROR : potato2: Couldn't move: object not found 2026/03/04 05:07:29 INFO : potato3: Moved (server-side) 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/03/04 05:07:29 DEBUG : drime root 'rclone-test-fulobam6cixe': Purge remote --- FAIL: TestServerSideMove (6.73s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" 2026/03/04 05:07:29 DEBUG : Creating backend with remote "TestDrime:rclone-test-cenujix2hixo" 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 sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-gurebit6jasa' -> drime root 'rclone-test-cenujix2hixo' 2026/03/04 05:07:42 DEBUG : empty space: Excluded (Size Filter) 2026/03/04 05:07:42 DEBUG : empty space: Excluded 2026/03/04 05:07:43 DEBUG : empty space: Excluded (Size Filter) 2026/03/04 05:07:43 DEBUG : empty space: Excluded 2026/03/04 05:07:43 DEBUG : potato2: Need to transfer - File not found at Destination 2026/03/04 05:07:43 DEBUG : potato3: size = 68 (drime root 'rclone-test-gurebit6jasa') 2026/03/04 05:07:43 DEBUG : potato3: size = 60 (drime root 'rclone-test-cenujix2hixo') 2026/03/04 05:07:43 DEBUG : potato3: Sizes differ 2026/03/04 05:07:43 DEBUG : drime root 'rclone-test-cenujix2hixo': Waiting for checks to finish 2026/03/04 05:07:43 DEBUG : drime root 'rclone-test-cenujix2hixo': Waiting for transfers to finish 2026/03/04 05:07:43 INFO : potato3: Deleted 2026/03/04 05:07:43 ERROR : potato2: Couldn't move: object not found 2026/03/04 05:07:43 INFO : potato3: Moved (server-side) 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/03/04 05:07:43 DEBUG : drime root 'rclone-test-cenujix2hixo': Purge remote 2026/03/04 05:07:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/03/04 05:07:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/04 05:07:44 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestServerSideMoveWithFilter (23.22s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "drime root 'rclone-test-gurebit6jasa'", Local "Local file system at /tmp/rclone3043630884", Modify Window "876000h0m0s" 2026/03/04 05:07:53 DEBUG : Creating backend with remote "TestDrime:rclone-test-tawituq3fava" 2026/03/04 05:07:55 INFO : tomatoDir: Making directory sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-gurebit6jasa' -> drime root 'rclone-test-tawituq3fava' 2026/03/04 05:07:58 DEBUG : drime root 'rclone-test-tawituq3fava': Using server-side directory move 2026/03/04 05:07:58 INFO : drime root 'rclone-test-tawituq3fava': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/03/04 05:07:59 DEBUG : potato2: Need to transfer - File not found at Destination 2026/03/04 05:07:59 DEBUG : empty space: size = 1 OK 2026/03/04 05:07:59 DEBUG : potato3: size = 68 (drime root 'rclone-test-gurebit6jasa') 2026/03/04 05:07:59 DEBUG : potato3: size = 60 (drime root 'rclone-test-tawituq3fava') 2026/03/04 05:07:59 DEBUG : potato3: Sizes differ 2026/03/04 05:07:59 DEBUG : empty space: Sizes identical 2026/03/04 05:07:59 DEBUG : empty space: Unchanged skipping 2026/03/04 05:07:59 DEBUG : drime root 'rclone-test-tawituq3fava': Waiting for checks to finish 2026/03/04 05:07:59 INFO : potato3: Deleted 2026/03/04 05:07:59 INFO : empty space: Deleted 2026/03/04 05:07:59 DEBUG : drime root 'rclone-test-tawituq3fava': Waiting for transfers to finish 2026/03/04 05:07:59 INFO : potato2: Moved (server-side) 2026/03/04 05:08:00 INFO : potato3: Moved (server-side) 2026/03/04 05:08:00 INFO : tomatoDir: Removing directory 2026/03/04 05:08:00 DEBUG : drime root 'rclone-test-gurebit6jasa': deleted 1 directories 2026/03/04 05:08:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-fudacef6cipa" 2026/03/04 05:08:02 INFO : tomatoDir: Making directory 2026/03/04 05:08:03 DEBUG : drime root 'rclone-test-fudacef6cipa': Using server-side directory move panic: test timed out after 2h0m0s running tests: TestServerSideMoveDeleteEmptySourceDirs (1h57m52s) goroutine 815114 [running]: testing.(*M).startAlarm.func1() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2682 +0x345 created by time.goFunc /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 118 minutes]: testing.(*T).Run(0xc000582700, {0x29d6ca8?, 0xc000787ae8?}, 0x2ae1d98) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2005 +0x485 testing.runTests.func1(0xc000582700) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2477 +0x37 testing.tRunner(0xc000582700, 0xc000787c28) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1934 +0xea testing.runTests(0xc0006d4048, {0x449d6e0, 0x6c, 0x6c}, {0x8563e0?, 0x44d9ff0?, 0x44b5f60?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2475 +0x4b4 testing.(*M).Run(0xc0004b79a0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2337 +0x63a github.com/rclone/rclone/fstest.TestMain(0xc0004b79a0) /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 +0xa6 goroutine 14 [syscall, 120 minutes]: os/signal.signal_recv() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/os/signal/signal.go:152 +0x1f goroutine 15 [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 1575 [select]: net/http.(*http2ClientConn).roundTrip(0xc00050a380, 0xc00045de00, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8680 +0x53f net/http.(*http2ClientConn).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8595 net/http.(*http2Transport).RoundTripOpt(0xc0006dbd60, 0xc00045de00, {0xf8?, 0xa7?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:7921 +0x1ec net/http.(*http2Transport).RoundTrip(0xc000cc2820?, 0xc000140f50?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:7872 +0x17 net/http.(*Transport).roundTrip(0xc000cc2820, 0xc00099c640) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:694 +0xa7f net/http.(*Transport).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/roundtrip.go:33 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000e3e1e0, 0xc00099c640) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x34a net/http.send(0xc00099c640, {0x2e4a660, 0xc000e3e1e0}, {0xc00080ae01?, 0x7097b0?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc000254060, 0xc00099c640, {0x41ecf4?, 0x1e?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc000254060, 0xc00099c640) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:729 +0x9c9 net/http.(*Client).Do(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc00093d860, {0x2e73ad8, 0x44d9a20}, 0xc00080b560) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:371 +0xd87 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc00093d860, {0x2e73ad8, 0x44d9a20}, 0xc00098a580?, {0x22a96a0?, 0xc00094c120?}, {0x22a96e0, 0xc000f44400}, 0x0?, 0x2ae3590, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x40b github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:522 github.com/rclone/rclone/backend/drime.(*Fs).patch.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:794 +0x8d github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0x50?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc000e3e240, 0xc00093c050, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:228 +0xd2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc000e3e240, 0xc00093c050) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:247 +0x92 github.com/rclone/rclone/backend/drime.(*Fs).patch(0xc0006dbcc0, {0x2e73ad8, 0x44d9a20}, {0xc0006b6ba6, 0x9}, {0x295912f, 0x4}, {0xc000e3c60a, 0x18}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:793 +0x387 github.com/rclone/rclone/backend/drime.(*Fs).rename(0xc0006dbcc0, {0x2e73ad8, 0x44d9a20}, {0xc0006b6ba6, 0x9}, {0xc000e3c60a, 0x18}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:805 +0x96 github.com/rclone/rclone/backend/drime.(*Fs).moveTo(0xc0006dbcc0, {0x2e73ad8, 0x44d9a20}, {0xc0006b6ba6, 0x9}, {0xc00056d5fa, 0x18}, {0xc000e3c60a, 0x18}, {0x0, ...}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:865 +0x2c9 github.com/rclone/rclone/backend/drime.(*Fs).DirMove(0xc0006dbcc0, {0x2e73ad8, 0x44d9a20}, {0x2e8af70?, 0xc0006dab40}, {0x0, 0x0}, {0x0?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:943 +0x125 github.com/rclone/rclone/fs/sync.MoveDir({0x2e73ad8, 0x44d9a20}, {0x2e8af70, 0xc0006dbcc0}, {0x2e8af70, 0xc0006dab40}, 0x1, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1403 +0x24e github.com/rclone/rclone/fs/sync.testServerSideMove({0x2e73ad8, 0x44d9a20}, 0xc000a94c40, 0xc00037fe30, 0x0, 0x1) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1775 +0xbc5 github.com/rclone/rclone/fs/sync.TestServerSideMoveDeleteEmptySourceDirs(0xc000a94c40) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1932 +0x48 testing.tRunner(0xc000a94c40, 0x2ae1d98) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1934 +0xea created by testing.(*T).Run in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1997 +0x465 goroutine 815112 [runnable]: crypto/internal/fips140/aes.encryptBlock(0xc000926e00?, {0xc000094648?, 0x10?, 0x10?}, {0xc000094668?, 0x10?, 0x10?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/internal/fips140/aes/aes_asm.go:82 +0xa8 crypto/internal/fips140/aes.EncryptBlockInternal(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/internal/fips140/aes/aes.go:130 crypto/internal/fips140/aes/gcm.open({0xc0006ca385, 0xfa, 0xd7b}, 0xc000926e00, {0xc0001511c0?, 0x1fdad532aeebaf?, 0x0?}, {0xc0006ca385, 0x10a, 0xd7b}, ...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/internal/fips140/aes/gcm/gcm_asm.go:117 +0x1c7 crypto/internal/fips140/aes/gcm.(*GCM).Open(0xc000926e00, {0xc0006ca385?, 0xc262149d35ee7bce?, 0x6d26f02df11?}, {0xc0001511c0, 0xc, 0xc}, {0xc0006ca385, 0x10a, 0xd7b}, ...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/internal/fips140/aes/gcm/gcm.go:119 +0x277 crypto/internal/fips140/aes/gcm.(*GCMForTLS13).Open(0xc000926e00, {0xc0006ca385, 0x0, 0xd7b}, {0xc0001511c0, 0xc, 0xc}, {0xc0006ca385, 0x10a, 0xd7b}, ...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/internal/fips140/aes/gcm/gcm_nonces.go:202 +0xd8 crypto/tls.(*xorNonceAEAD).Open(0xc0001511c0, {0xc0006ca385?, 0xc0000948d8?, 0x7513bb?}, {0xc00048ba38, 0x8, 0x7220c846b5c0?}, {0xc0006ca385, 0x10a, 0xd7b}, ...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/cipher_suites.go:509 +0xd1 crypto/tls.(*halfConn).decrypt(0xc00048b9f8, {0xc0006ca380, 0x10f, 0xd80}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:381 +0x7ed crypto/tls.(*Conn).readRecordOrCCS(0xc00048b888, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:687 +0xe25 crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:589 crypto/tls.(*Conn).Read(0xc00048b888, {0xc000969000, 0x1000, 0xc0000bb810?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:1392 +0x145 bufio.(*Reader).Read(0xc00013b1a0, {0xc00068a4a0, 0x9, 0x50?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bufio/bufio.go:245 +0x197 io.ReadAtLeast({0x2e48f80, 0xc00013b1a0}, {0xc00068a4a0, 0x9, 0x9}, 0x9) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/io/io.go:335 +0x8e io.ReadFull(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/io/io.go:354 net/http.http2readFrameHeader({0xc00068a4a0, 0x9, 0xc000140fa0?}, {0x2e48f80?, 0xc00013b1a0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:1811 +0x65 net/http.(*http2Framer).ReadFrame(0xc00068a460) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:2078 +0x7d net/http.(*http2clientConnReadLoop).run(0xc000094fa8) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:9539 +0xda net/http.(*http2ClientConn).readLoop(0xc00050a380) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:9408 +0x79 created by net/http.(*http2Transport).newClientConn in goroutine 815111 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8192 +0xde5 goroutine 1764 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0003ae1e0, {0x2e73bb8, 0xc00061e0f0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:352 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 1575 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:389 +0x11d goroutine 815113 [select]: net/http.(*http2clientStream).writeRequest(0xc0008d6480, 0xc00045de00, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8850 +0xc65 net/http.(*http2clientStream).doRequest(0xc0008d6480, 0x2e81f70?, 0xc00048b888?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8711 +0x56 created by net/http.(*http2ClientConn).roundTrip in goroutine 1575 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8616 +0x485 "./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCopyOverSelf|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncBackupDir|TestSyncCompareDest|TestSyncCopyDest|TestSyncIgnoreTimes|TestSyncWithTrackRenamesStrategyLeaf)$'" - Finished ERROR in 2h0m0.889720701s (try 3/5): exit status 2: Failed [TestCopyOverSelf TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf TestSyncIgnoreTimes TestSyncAfterChangingFilesSizeOnly TestSyncAfterChangingContentsOnly TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter]