"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Starting (try 1/5) 2026/02/25 02:56:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo" 2026/02/25 02:56:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/25 02:57:05 DEBUG : Creating backend with remote "/tmp/rclone1483561789" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 02:57:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 02:57:13 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2026/02/25 02:57:13 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 02:57:13 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (11.58s) === RUN TestCopy run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 02:57:16 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/25 02:57:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 02:57:17 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 02:57:17 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 02:57:29 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 02:57:29 INFO : sub dir/hello world: Copied (new) 2026/02/25 02:57:33 DEBUG : drime root 'rclone-test-zitebes8cofo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (29.57s) === RUN TestCopyMetadata run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (3.07s) === RUN TestCopyMetadataNoEmptyDirs run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (2.71s) === RUN TestCopyMissingDirectory run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 02:57:52 DEBUG : Creating backend with remote "/non-existing" 2026/02/25 02:57:53 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2026/02/25 02:57:53 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 02:57:53 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (3.33s) === RUN TestCopyNoTraverse run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 02:57:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 02:57:56 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 02:57:56 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 02:58:07 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 02:58:07 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (23.24s) === RUN TestCopyNoTraverseDeadlock run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:222: Only runs on local --- SKIP: TestCopyNoTraverseDeadlock (2.44s) === RUN TestCopyCheckFirst run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 02:58:21 INFO : drime root 'rclone-test-zitebes8cofo': Running all checks before starting transfers 2026/02/25 02:58:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 02:58:24 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 02:58:24 INFO : drime root 'rclone-test-zitebes8cofo': Checks finished, now starting transfers 2026/02/25 02:58:24 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 02:58:50 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 02:58:50 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (50.92s) === RUN TestSyncNoTraverse run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 02:59:11 ERROR : Ignoring --no-traverse with sync 2026/02/25 02:59:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 02:59:14 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 02:59:14 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 02:59:50 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 02:59:50 INFO : sub dir/hello world: Copied (new) 2026/02/25 02:59:50 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (70.66s) === RUN TestCopyWithDepth run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:00:24 DEBUG : hello world2: Need to transfer - File not found at Destination 2026/02/25 03:00:24 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:00:24 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:00:38 DEBUG : hello world2: size = 12 OK 2026/02/25 03:00:38 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (23.40s) === RUN TestCopyWithFilesFrom run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:00:46 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:00:46 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:00:46 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:00:55 DEBUG : potato2: size = 11 OK 2026/02/25 03:00:55 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (16.93s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:01:04 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:01:04 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:01:04 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:01:11 DEBUG : potato2: size = 11 OK 2026/02/25 03:01:11 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (17.56s) === RUN TestCopyEmptyDirectories run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:01:20 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/25 03:01:20 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/25 03:01:20 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/02/25 03:01:20 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/25 03:01:21 INFO : sub dir2: Making directory 2026/02/25 03:01:25 INFO : sub dir: Making directory 2026/02/25 03:01:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:01:28 INFO : sub dir2/sub sub dir2: Making directory 2026/02/25 03:01:30 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:01:30 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:01:37 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:01:37 INFO : sub dir/hello world: Copied (new) 2026/02/25 03:01:39 DEBUG : drime root 'rclone-test-zitebes8cofo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (23.15s) === RUN TestCopyNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:01:43 INFO : sub dir2: Making directory 2026/02/25 03:01:43 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/25 03:01:43 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/25 03:01:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:01:44 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:01:44 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:01:53 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:01:53 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (24.47s) === RUN TestMoveEmptyDirectories run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:02:08 DEBUG : sub dir2: Making directory with metadata 2026/02/25 03:02:08 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/25 03:02:09 INFO : sub dir2: Making directory 2026/02/25 03:02:13 INFO : sub dir: Making directory 2026/02/25 03:02:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:02:18 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:02:18 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:02:33 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:02:33 INFO : sub dir/hello world: Copied (new) 2026/02/25 03:02:33 INFO : sub dir/hello world: Deleted 2026/02/25 03:02:41 DEBUG : drime root 'rclone-test-zitebes8cofo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2026/02/25 03:02:45 DEBUG : drime root 'rclone-test-zitebes8cofo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (87.34s) === RUN TestSyncNoUpdateDirModtime run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:438: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (11.39s) === RUN TestMoveNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:03:46 INFO : sub dir2: Making directory 2026/02/25 03:03:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:03:52 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:03:52 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:04:33 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:04:33 INFO : sub dir/hello world: Copied (new) 2026/02/25 03:04:33 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (99.20s) === RUN TestSyncEmptyDirectories run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:05:26 DEBUG : sub dir2: Making directory with metadata 2026/02/25 03:05:26 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/25 03:05:26 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/25 03:05:33 INFO : sub dir2: Making directory 2026/02/25 03:06:08 INFO : sub dir: Making directory 2026/02/25 03:06:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:06:28 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:06:28 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:06:47 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:06:47 INFO : sub dir/hello world: Copied (new) 2026/02/25 03:06:47 DEBUG : Waiting for deletions to finish 2026/02/25 03:07:02 DEBUG : drime root 'rclone-test-zitebes8cofo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (134.44s) === RUN TestSyncSetDelayedModTimes run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (6.06s) === RUN TestSyncNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:07:46 INFO : sub dir2: Making directory 2026/02/25 03:07:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:07:49 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:07:49 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:08:09 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:08:09 INFO : sub dir/hello world: Copied (new) 2026/02/25 03:08:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (37.51s) === RUN TestServerSideCopy run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:08:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-vevonob0naxa" sync_test.go:620: Server side copy (if possible) drime root 'rclone-test-zitebes8cofo' -> drime root 'rclone-test-vevonob0naxa' 2026/02/25 03:08:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:08:58 DEBUG : drime root 'rclone-test-vevonob0naxa': Waiting for checks to finish 2026/02/25 03:08:58 DEBUG : drime root 'rclone-test-vevonob0naxa': Waiting for transfers to finish 2026/02/25 03:09:14 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:09:14 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/25 03:09:18 DEBUG : drime root 'rclone-test-vevonob0naxa': Purge remote --- PASS: TestServerSideCopy (80.08s) === RUN TestCopyOverSelf run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:10:25 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone1483561789) 2026/02/25 03:10:25 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 03:10:25 DEBUG : sub dir/hello world: Sizes differ 2026/02/25 03:10:25 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:10:25 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:10:39 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/25 03:10:42 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 --- FAIL: TestCopyOverSelf (69.77s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:11:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-pewaceb4novu" 2026/02/25 03:11:08 DEBUG : Config file has changed externally - reloading sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-zitebes8cofo' -> drime root 'rclone-test-pewaceb4novu' 2026/02/25 03:11:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:11:12 DEBUG : drime root 'rclone-test-pewaceb4novu': Waiting for checks to finish 2026/02/25 03:11:12 DEBUG : drime root 'rclone-test-pewaceb4novu': Waiting for transfers to finish 2026/02/25 03:11:28 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:11:28 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/25 03:11:59 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-zitebes8cofo'": failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/02/25 03:12:01 DEBUG : drime root 'rclone-test-pewaceb4novu': Purge remote --- FAIL: TestServerSideCopyOverSelf (82.50s) === RUN TestMoveOverSelf run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:12:43 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone1483561789) 2026/02/25 03:12:43 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 03:12:43 DEBUG : sub dir/hello world: Sizes differ 2026/02/25 03:12:43 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:12:43 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:12:55 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/25 03:12:58 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/02/25 03:12:58 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 --- FAIL: TestMoveOverSelf (51.10s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:13:38 DEBUG : Creating backend with remote "TestDrime:rclone-test-lisudog6yiyu" sync_test.go:701: Server side copy (if possible) drime root 'rclone-test-zitebes8cofo' -> drime root 'rclone-test-lisudog6yiyu' 2026/02/25 03:13:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:13:57 DEBUG : drime root 'rclone-test-lisudog6yiyu': Waiting for checks to finish 2026/02/25 03:13:57 DEBUG : drime root 'rclone-test-lisudog6yiyu': Waiting for transfers to finish 2026/02/25 03:14:32 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:14:32 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/25 03:15:08 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-zitebes8cofo'": failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/02/25 03:15:15 DEBUG : drime root 'rclone-test-lisudog6yiyu': Purge remote --- FAIL: TestServerSideMoveOverSelf (174.34s) === RUN TestCopyAfterDelete run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:16:33 ERROR : error listing: directory not found 2026/02/25 03:16:44 INFO : Local file system at /tmp/rclone1483561789: Making directory 2026/02/25 03:16:55 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:16:55 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (87.89s) === RUN TestCopyRedownload run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:18:08 DEBUG : Added delayed dir = "sub dir", newDst= 2026/02/25 03:18:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:18:13 DEBUG : Local file system at /tmp/rclone1483561789: Waiting for checks to finish 2026/02/25 03:18:13 DEBUG : Local file system at /tmp/rclone1483561789: Waiting for transfers to finish 2026/02/25 03:18:19 DEBUG : sub dir/hello world.9076d4bf.partial: size = 11 OK 2026/02/25 03:18:19 DEBUG : sub dir/hello world.9076d4bf.partial: renamed to: sub dir/hello world 2026/02/25 03:18:19 INFO : sub dir/hello world: Copied (new) 2026/02/25 03:18:19 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyRedownload (83.25s) === RUN TestSyncBasedOnCheckSum run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:18:59 DEBUG : check sum: Need to transfer - File not found at Destination 2026/02/25 03:18:59 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:18:59 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:19:14 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:19:14 ERROR : check sum: Failed to copy: failed to upload file: Error "520 (520): \n\n\n\n \n\n\ndrime.cloud | 520: Web server is returning an unknown error\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Web server is returning an unknown error\n Error code 520\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:19:14 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

There is an unknown connection issue between Cloudflare and the origin web server. As a result, the web page can not be displayed.

\n
\n
\n

What can I do?

\n \n

If you are a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you are the owner of this website:

\n

There is an issue between Cloudflare's cache and your origin web server. Cloudflare monitors for these errors and automatically investigates the cause. To help support the investigation, you can pull the corresponding error log from your web server and submit it our support team. Please include the Ray ID (which is at the bottom of this error page). Additional troubleshooting resources.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d340f19fe69d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" 2026/02/25 03:19:14 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting files as there were IO errors 2026/02/25 03:19:14 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting directories as there were IO errors sync_test.go:784: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:784 Error: Received unexpected error: failed to upload file: Error "520 (520): \n\n\n\n \n\n\ndrime.cloud | 520: Web server is returning an unknown error\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Web server is returning an unknown error\n Error code 520\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:19:14 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

There is an unknown connection issue between Cloudflare and the origin web server. As a result, the web page can not be displayed.

\n
\n
\n

What can I do?

\n \n

If you are a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you are the owner of this website:

\n

There is an issue between Cloudflare's cache and your origin web server. Cloudflare monitors for these errors and automatically investigates the cause. To help support the investigation, you can pull the corresponding error log from your web server and submit it our support team. Please include the Ray ID (which is at the bottom of this error page). Additional troubleshooting resources.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d340f19fe69d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" Test: TestSyncBasedOnCheckSum 2026/02/25 03:19:14 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:19:14 ERROR : error listing: couldn't list files: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:19:14 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d340f5b1f3dd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" run.go:169: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1308 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1572 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1928 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/panic.go:615 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1013 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:784 Error: Received unexpected error: couldn't list files: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:19:14 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d340f5b1f3dd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" Test: TestSyncBasedOnCheckSum --- FAIL: TestSyncBasedOnCheckSum (21.46s) === RUN TestSyncSizeOnly run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:817 Error: Should be true Test: TestSyncSizeOnly Messages: listing wrong, want sizeonly (6) got check sum (1), 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:817 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/asm_amd64.s:1693 Error: Should be true Test: TestSyncSizeOnly Messages: Unexpected file "check sum" 2026/02/25 03:19:28 DEBUG : check sum: size = 1 OK 2026/02/25 03:19:28 DEBUG : check sum: Sizes identical 2026/02/25 03:19:28 DEBUG : sizeonly: Need to transfer - File not found at Destination 2026/02/25 03:19:28 DEBUG : check sum: Unchanged skipping 2026/02/25 03:19:28 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:19:28 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:19:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:19:35 ERROR : sizeonly: Failed to copy: couldn't list files: Error "521 (521): \n\n\n\n \n\n\ndrime.cloud | 521: Web server is down\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Web server is down\n Error code 521\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:19:35 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server is not returning a connection. As a result, the web page is not displaying.

\n
\n
\n

What can I do?

\n \n

If you are a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you are the owner of this website:

\n

Contact your hosting provider letting them know your web server is not responding. Additional troubleshooting information.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d340fb5aa4cd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" 2026/02/25 03:19:35 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting files as there were IO errors 2026/02/25 03:19:35 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting directories as there were IO errors sync_test.go:822: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:822 Error: Received unexpected error: couldn't list files: Error "521 (521): \n\n\n\n \n\n\ndrime.cloud | 521: Web server is down\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Web server is down\n Error code 521\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:19:35 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server is not returning a connection. As a result, the web page is not displaying.

\n
\n
\n

What can I do?

\n \n

If you are a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you are the owner of this website:

\n

Contact your hosting provider letting them know your web server is not responding. Additional troubleshooting information.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d340fb5aa4cd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" Test: TestSyncSizeOnly 2026/02/25 03:19:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:19:35 ERROR : error listing: couldn't list files: Error "521 (521): \n\n\n\n \n\n\ndrime.cloud | 521: Web server is down\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Web server is down\n Error code 521\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:19:35 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server is not returning a connection. As a result, the web page is not displaying.

\n
\n
\n

What can I do?

\n \n

If you are a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you are the owner of this website:

\n

Contact your hosting provider letting them know your web server is not responding. Additional troubleshooting information.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d340fdf1957d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" run.go:169: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1308 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1572 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1928 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/panic.go:615 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1013 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:822 Error: Received unexpected error: couldn't list files: Error "521 (521): \n\n\n\n \n\n\ndrime.cloud | 521: Web server is down\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Web server is down\n Error code 521\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:19:35 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server is not returning a connection. As a result, the web page is not displaying.

\n
\n
\n

What can I do?

\n \n

If you are a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you are the owner of this website:

\n

Contact your hosting provider letting them know your web server is not responding. Additional troubleshooting information.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d340fdf1957d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" Test: TestSyncSizeOnly --- FAIL: TestSyncSizeOnly (21.07s) === RUN TestSyncIgnoreSize run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:855 Error: Should be true Test: TestSyncIgnoreSize Messages: listing wrong, want ignore-size (8) got check sum (1), ignore-size (8), 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:855 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/asm_amd64.s:1693 Error: Should be true Test: TestSyncIgnoreSize Messages: Unexpected file "check sum" 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:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:855 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/asm_amd64.s:1693 Error: Should be true Test: TestSyncIgnoreSize Messages: Unexpected file "sizeonly" 2026/02/25 03:19:48 DEBUG : check sum: Sizes identical 2026/02/25 03:19:48 DEBUG : ignore-size: Need to transfer - File not found at Destination 2026/02/25 03:19:48 DEBUG : sizeonly: Need to transfer - File not found at Destination 2026/02/25 03:19:48 DEBUG : check sum: Unchanged skipping 2026/02/25 03:19:48 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:19:48 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:20:05 INFO : sizeonly: Copied (new) 2026/02/25 03:20:11 INFO : ignore-size: Copied (new) 2026/02/25 03:20:11 DEBUG : Waiting for deletions to finish sync_test.go:864: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:864 Error: Not equal: expected: 1 actual : 2 Test: TestSyncIgnoreSize fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/25 03:20:22 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:22 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:22 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3410e4e8f2d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/25 03:20:22 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:22 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:22 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3411064dd7d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:22 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/25 03:20:22 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:22 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:22 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3411067e3fd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:22 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/25 03:20:22 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:22 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:22 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d341106beaed37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:22 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/25 03:20:22 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:22 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:22 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3411073f96d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:22 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/25 03:20:23 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:23 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:23 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3411083950d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:23 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/25 03:20:23 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:23 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:23 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d34110a3ca1d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:23 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/25 03:20:24 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:24 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:24 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d34110e4ccfd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:24 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/25 03:20:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:25 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:25 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3411164cc1d37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:25 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/25 03:20:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/25 03:20:27 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:27 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3411264aabd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n") 2026/02/25 03:20:27 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/25 03:20:27 ERROR : error listing: couldn't list files: Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:27 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3411264aabd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" fstest.go:282: Error listing: couldn't list files: Error "502 Bad Gateway (502): \n\n\n\n \n\n\ndrime.cloud | 502: Bad gateway\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Bad gateway\n Error code 502\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-25 03:20:27 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

The web server reported a bad gateway error.

\n
\n
\n

What can I do?

\n

Please try again in a few minutes.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9d3411264aabd37e\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" 2026/02/25 03:20:33 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/25 03:20:43 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/25 03:20:48 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/25 03:20:51 DEBUG : pacer: Reducing sleep to 640ms 2026/02/25 03:20:52 DEBUG : pacer: Reducing sleep to 320ms --- FAIL: TestSyncIgnoreSize (76.81s) === RUN TestSyncIgnoreTimes run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:20:53 DEBUG : pacer: Reducing sleep to 160ms 2026/02/25 03:20:58 DEBUG : pacer: Reducing sleep to 80ms 2026/02/25 03:20:58 DEBUG : pacer: Reducing sleep to 40ms 2026/02/25 03:20:58 DEBUG : pacer: Reducing sleep to 20ms 2026/02/25 03:20:58 DEBUG : existing: size = 6 OK 2026/02/25 03:20:58 DEBUG : existing: Sizes identical 2026/02/25 03:20:58 DEBUG : existing: Unchanged skipping 2026/02/25 03:20:58 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:20:58 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:20:58 DEBUG : Waiting for deletions to finish 2026/02/25 03:20:58 INFO : There was nothing to transfer 2026/02/25 03:20:59 DEBUG : pacer: Reducing sleep to 10ms 2026/02/25 03:20:59 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2026/02/25 03:20:59 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:20:59 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:21:08 DEBUG : existing: Removing old object on successful upload 2026/02/25 03:21:09 ERROR : existing: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/02/25 03:21:09 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting files as there were IO errors 2026/02/25 03:21:09 ERROR : drime root 'rclone-test-zitebes8cofo': 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 --- FAIL: TestSyncIgnoreTimes (19.48s) === RUN TestSyncIgnoreExisting run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:21:12 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/25 03:21:12 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:21:12 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:21:32 DEBUG : existing: size = 6 OK 2026/02/25 03:21:32 INFO : existing: Copied (new) 2026/02/25 03:21:32 DEBUG : Waiting for deletions to finish 2026/02/25 03:21:41 DEBUG : existing: Destination exists, skipping 2026/02/25 03:21:41 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:21:41 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:21:41 DEBUG : Waiting for deletions to finish 2026/02/25 03:21:41 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (63.03s) === RUN TestSyncIgnoreErrors run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:23:15 INFO : d: Making directory 2026/02/25 03:23:42 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/25 03:23:48 DEBUG : c/non empty space: size = 5 OK 2026/02/25 03:23:48 DEBUG : c/non empty space: Sizes identical 2026/02/25 03:23:48 DEBUG : c/non empty space: Unchanged skipping 2026/02/25 03:23:48 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:23:48 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:24:11 DEBUG : a/potato2: size = 60 OK 2026/02/25 03:24:11 INFO : a/potato2: Copied (new) 2026/02/25 03:24:11 DEBUG : Waiting for deletions to finish 2026/02/25 03:24:17 INFO : b/potato: Deleted 2026/02/25 03:24:17 INFO : d: Removing directory 2026/02/25 03:24:29 INFO : b: Removing directory 2026/02/25 03:24:44 DEBUG : drime root 'rclone-test-zitebes8cofo': deleted 2 directories --- PASS: TestSyncIgnoreErrors (197.78s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:25:47 DEBUG : empty space: size = 1 OK 2026/02/25 03:25:47 DEBUG : empty space: Sizes identical 2026/02/25 03:25:47 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:25:47 DEBUG : empty space: Unchanged skipping 2026/02/25 03:25:47 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:25:47 DEBUG : Waiting for deletions to finish 2026/02/25 03:25:47 INFO : There was nothing to transfer 2026/02/25 03:25:51 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:25:51 DEBUG : empty space: size = 1 OK 2026/02/25 03:25:51 DEBUG : empty space: Sizes identical 2026/02/25 03:25:51 DEBUG : empty space: Unchanged skipping 2026/02/25 03:25:51 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:25:51 DEBUG : Waiting for deletions to finish 2026/02/25 03:25:51 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (23.77s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:1046: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.18s) === RUN TestSyncDoesntUpdateModtime run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:1072: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncDoesntUpdateModtime (2.58s) === RUN TestSyncAfterAddingAFile run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:26:18 DEBUG : empty space: size = 1 OK 2026/02/25 03:26:18 DEBUG : empty space: Sizes identical 2026/02/25 03:26:18 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/25 03:26:18 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:26:18 DEBUG : empty space: Unchanged skipping 2026/02/25 03:26:18 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:26:30 DEBUG : potato: size = 60 OK 2026/02/25 03:26:30 INFO : potato: Copied (new) 2026/02/25 03:26:30 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (37.20s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:27:00 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:27:00 DEBUG : potato: size = 21 (Local file system at /tmp/rclone1483561789) 2026/02/25 03:27:00 DEBUG : potato: size = 60 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 03:27:00 DEBUG : potato: Sizes differ 2026/02/25 03:27:00 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:27:08 DEBUG : potato: Removing old object on successful upload 2026/02/25 03:27:10 ERROR : potato: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/02/25 03:27:10 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting files as there were IO errors 2026/02/25 03:27:10 ERROR : drime root 'rclone-test-zitebes8cofo': 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 --- FAIL: TestSyncAfterChangingFilesSizeOnly (38.16s) === RUN TestSyncAfterChangingContentsOnly run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:1136: ModTimeNotSupported so forcing file to be a different size 2026/02/25 03:27:41 DEBUG : potato: size = 21 (Local file system at /tmp/rclone1483561789) 2026/02/25 03:27:41 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:27:41 DEBUG : potato: size = 36 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 03:27:41 DEBUG : potato: Sizes differ 2026/02/25 03:27:41 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:27:51 DEBUG : potato: Removing old object on successful upload 2026/02/25 03:27:55 ERROR : potato: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/02/25 03:27:55 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting files as there were IO errors 2026/02/25 03:27:55 ERROR : drime root 'rclone-test-zitebes8cofo': 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 --- FAIL: TestSyncAfterChangingContentsOnly (54.77s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:28:44 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:28:44 DEBUG : empty space: size = 1 OK 2026/02/25 03:28:44 DEBUG : empty space: Sizes identical 2026/02/25 03:28:44 DEBUG : empty space: Unchanged skipping 2026/02/25 03:28:44 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2026/02/25 03:28:44 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:28:44 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:28:44 DEBUG : Waiting for deletions to finish 2026/02/25 03:28:44 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (52.09s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:29:47 DEBUG : empty space: size = 1 OK 2026/02/25 03:29:47 DEBUG : empty space: Sizes identical 2026/02/25 03:29:47 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:29:47 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:29:47 DEBUG : empty space: Unchanged skipping 2026/02/25 03:29:47 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:30:04 DEBUG : potato2: size = 60 OK 2026/02/25 03:30:04 INFO : potato2: Copied (new) 2026/02/25 03:30:04 DEBUG : Waiting for deletions to finish 2026/02/25 03:30:06 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (79.15s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:31:11 INFO : d: Making directory 2026/02/25 03:31:15 INFO : d/e: Making directory 2026/02/25 03:31:33 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/25 03:31:34 DEBUG : c/non empty space: size = 5 OK 2026/02/25 03:31:34 DEBUG : c/non empty space: Sizes identical 2026/02/25 03:31:34 DEBUG : c/non empty space: Unchanged skipping 2026/02/25 03:31:41 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:31:41 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:31:57 DEBUG : a/potato2: size = 60 OK 2026/02/25 03:31:57 INFO : a/potato2: Copied (new) 2026/02/25 03:31:57 DEBUG : Waiting for deletions to finish 2026/02/25 03:32:08 INFO : b/potato: Deleted 2026/02/25 03:32:08 INFO : d/e: Removing directory 2026/02/25 03:32:21 INFO : d: Removing directory 2026/02/25 03:32:30 INFO : b: Removing directory 2026/02/25 03:32:49 DEBUG : drime root 'rclone-test-zitebes8cofo': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (186.16s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:34:18 INFO : d: Making directory 2026/02/25 03:34:30 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/25 03:34:32 DEBUG : c/non empty space: size = 5 OK 2026/02/25 03:34:32 DEBUG : c/non empty space: Sizes identical 2026/02/25 03:34:32 DEBUG : c/non empty space: Unchanged skipping 2026/02/25 03:34:32 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:34:32 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:34:49 DEBUG : a/potato2: size = 60 OK 2026/02/25 03:34:49 INFO : a/potato2: Copied (new) 2026/02/25 03:34:49 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting files as there were IO errors 2026/02/25 03:34:49 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (110.73s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:35:52 DEBUG : Waiting for deletions to finish 2026/02/25 03:35:56 DEBUG : empty space: size = 1 OK 2026/02/25 03:35:56 DEBUG : empty space: Sizes identical 2026/02/25 03:35:56 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:35:56 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:35:56 DEBUG : empty space: Unchanged skipping 2026/02/25 03:35:56 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:36:00 INFO : potato: Deleted 2026/02/25 03:36:11 DEBUG : potato2: size = 60 OK 2026/02/25 03:36:11 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (84.45s) === RUN TestSyncDeleteBefore run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:37:28 DEBUG : Waiting for deletions to finish 2026/02/25 03:37:34 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:37:34 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:37:40 INFO : potato: Deleted 2026/02/25 03:37:47 DEBUG : empty space: size = 1 OK 2026/02/25 03:37:47 DEBUG : empty space: Sizes identical 2026/02/25 03:37:47 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:37:47 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:37:47 DEBUG : empty space: Unchanged skipping 2026/02/25 03:37:47 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:38:07 DEBUG : potato2: size = 60 OK 2026/02/25 03:38:07 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (115.47s) === RUN TestCopyDeleteBefore run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:39:06 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:39:06 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:39:06 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:39:25 DEBUG : potato2: size = 19 OK 2026/02/25 03:39:25 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (88.05s) === RUN TestSyncWithExclude run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:40:51 DEBUG : enormous: Excluded (Size Filter) 2026/02/25 03:40:51 DEBUG : enormous: Excluded 2026/02/25 03:40:51 DEBUG : potato2: Excluded (Size Filter) 2026/02/25 03:40:51 DEBUG : potato2: Excluded 2026/02/25 03:40:55 DEBUG : potato2: Excluded (Size Filter) 2026/02/25 03:40:55 DEBUG : potato2: Excluded 2026/02/25 03:40:55 DEBUG : empty space: size = 1 OK 2026/02/25 03:40:55 DEBUG : empty space: Sizes identical 2026/02/25 03:40:55 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:40:55 DEBUG : empty space: Unchanged skipping 2026/02/25 03:40:55 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:40:55 DEBUG : Waiting for deletions to finish 2026/02/25 03:40:55 INFO : There was nothing to transfer 2026/02/25 03:40:58 DEBUG : enormous: Excluded (Size Filter) 2026/02/25 03:40:58 DEBUG : enormous: Excluded 2026/02/25 03:40:58 DEBUG : potato2: Excluded (Size Filter) 2026/02/25 03:40:58 DEBUG : potato2: Excluded 2026/02/25 03:41:01 DEBUG : potato2: Excluded (Size Filter) 2026/02/25 03:41:01 DEBUG : potato2: Excluded 2026/02/25 03:41:01 DEBUG : empty space: size = 1 OK 2026/02/25 03:41:01 DEBUG : empty space: Sizes identical 2026/02/25 03:41:01 DEBUG : empty space: Unchanged skipping 2026/02/25 03:41:01 DEBUG : Local file system at /tmp/rclone1483561789: Waiting for checks to finish 2026/02/25 03:41:01 DEBUG : Local file system at /tmp/rclone1483561789: Waiting for transfers to finish 2026/02/25 03:41:01 DEBUG : Waiting for deletions to finish 2026/02/25 03:41:01 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (65.05s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:42:01 DEBUG : enormous: Excluded (Size Filter) 2026/02/25 03:42:01 DEBUG : enormous: Excluded 2026/02/25 03:42:01 DEBUG : potato2: Excluded (Size Filter) 2026/02/25 03:42:01 DEBUG : potato2: Excluded 2026/02/25 03:42:05 DEBUG : empty space: size = 1 OK 2026/02/25 03:42:05 DEBUG : empty space: Sizes identical 2026/02/25 03:42:05 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:42:05 DEBUG : empty space: Unchanged skipping 2026/02/25 03:42:05 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:42:05 DEBUG : Waiting for deletions to finish 2026/02/25 03:42:10 INFO : enormous: Deleted 2026/02/25 03:42:10 INFO : potato2: Deleted 2026/02/25 03:42:10 INFO : There was nothing to transfer 2026/02/25 03:42:19 DEBUG : empty space: size = 1 OK 2026/02/25 03:42:19 DEBUG : empty space: Sizes identical 2026/02/25 03:42:19 DEBUG : empty space: Unchanged skipping 2026/02/25 03:42:19 DEBUG : Local file system at /tmp/rclone1483561789: Waiting for checks to finish 2026/02/25 03:42:19 DEBUG : Local file system at /tmp/rclone1483561789: Waiting for transfers to finish 2026/02/25 03:42:19 DEBUG : Waiting for deletions to finish 2026/02/25 03:42:19 INFO : potato2: Deleted 2026/02/25 03:42:19 INFO : enormous: Deleted 2026/02/25 03:42:19 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (80.20s) === RUN TestSyncWithUpdateOlder run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:1454: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncWithUpdateOlder (7.10s) === 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-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:1569: Can track renames: false 2026/02/25 03:42:39 ERROR : drime root 'rclone-test-zitebes8cofo': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/25 03:42:45 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/25 03:42:45 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/25 03:42:45 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:42:45 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:43:09 DEBUG : potato: size = 14 OK 2026/02/25 03:43:09 INFO : potato: Copied (new) 2026/02/25 03:43:20 DEBUG : yam: size = 11 OK 2026/02/25 03:43:20 INFO : yam: Copied (new) 2026/02/25 03:43:20 DEBUG : Waiting for deletions to finish 2026/02/25 03:43:25 ERROR : drime root 'rclone-test-zitebes8cofo': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/25 03:43:31 DEBUG : potato: size = 14 OK 2026/02/25 03:43:31 DEBUG : potato: Sizes identical 2026/02/25 03:43:31 DEBUG : yaml: Need to transfer - File not found at Destination 2026/02/25 03:43:31 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:43:31 DEBUG : potato: Unchanged skipping 2026/02/25 03:43:31 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:43:47 DEBUG : yaml: size = 11 OK 2026/02/25 03:43:47 INFO : yaml: Copied (new) 2026/02/25 03:43:47 DEBUG : Waiting for deletions to finish 2026/02/25 03:43:52 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (91.04s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:1641: Can track renames: false 2026/02/25 03:44:10 ERROR : drime root 'rclone-test-zitebes8cofo': Ignoring --track-renames as either the source or destination do not support modtime 2026/02/25 03:44:12 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/25 03:44:12 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/25 03:44:12 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:44:12 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:44:19 DEBUG : yam: size = 11 OK 2026/02/25 03:44:19 INFO : yam: Copied (new) 2026/02/25 03:44:24 DEBUG : potato: size = 14 OK 2026/02/25 03:44:24 INFO : potato: Copied (new) 2026/02/25 03:44:24 DEBUG : Waiting for deletions to finish 2026/02/25 03:44:25 ERROR : drime root 'rclone-test-zitebes8cofo': Ignoring --track-renames as either the source or destination do not support modtime 2026/02/25 03:44:26 DEBUG : potato: size = 14 OK 2026/02/25 03:44:26 DEBUG : potato: Sizes identical 2026/02/25 03:44:26 DEBUG : yaml: Need to transfer - File not found at Destination 2026/02/25 03:44:26 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:44:26 DEBUG : potato: Unchanged skipping 2026/02/25 03:44:26 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:44:33 DEBUG : yaml: size = 11 OK 2026/02/25 03:44:33 INFO : yaml: Copied (new) 2026/02/25 03:44:33 DEBUG : Waiting for deletions to finish 2026/02/25 03:44:34 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenamesStrategyModtime (27.54s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:1680: Can track renames: false 2026/02/25 03:44:39 INFO : drime root 'rclone-test-zitebes8cofo': Making map for --track-renames 2026/02/25 03:44:39 INFO : drime root 'rclone-test-zitebes8cofo': Finished making map for --track-renames 2026/02/25 03:44:39 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:44:39 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/02/25 03:44:39 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2026/02/25 03:44:39 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for renames to finish 2026/02/25 03:44:39 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:44:52 DEBUG : potato: size = 14 OK 2026/02/25 03:44:52 INFO : potato: Copied (new) 2026/02/25 03:44:53 DEBUG : sub/yam: size = 11 OK 2026/02/25 03:44:53 INFO : sub/yam: Copied (new) 2026/02/25 03:44:53 DEBUG : Waiting for deletions to finish 2026/02/25 03:45:08 DEBUG : potato: size = 14 OK 2026/02/25 03:45:08 DEBUG : potato: Sizes identical 2026/02/25 03:45:08 DEBUG : potato: Unchanged skipping 2026/02/25 03:45:11 INFO : drime root 'rclone-test-zitebes8cofo': Making map for --track-renames 2026/02/25 03:45:11 INFO : drime root 'rclone-test-zitebes8cofo': Finished making map for --track-renames 2026/02/25 03:45:11 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:45:11 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for renames to finish 2026/02/25 03:45:18 INFO : sub/yam: Moved (server-side) to: yam 2026/02/25 03:45:18 INFO : yam: Renamed from "sub/yam" 2026/02/25 03:45:18 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:45:18 DEBUG : Waiting for deletions to finish 2026/02/25 03:45:18 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyLeaf (48.32s) === RUN TestServerSideMoveLocal run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:45:26 DEBUG : Creating backend with remote "/tmp/rclone1483561789/dir1" 2026/02/25 03:45:26 DEBUG : Config file has changed externally - reloading 2026/02/25 03:45:26 DEBUG : Creating backend with remote "/tmp/rclone1483561789/dir2" 2026/02/25 03:45:26 DEBUG : Local file system at /tmp/rclone1483561789/dir2: Using server-side directory move 2026/02/25 03:45:26 INFO : Local file system at /tmp/rclone1483561789/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/25 03:45:26 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/25 03:45:26 DEBUG : Local file system at /tmp/rclone1483561789/dir2: Waiting for checks to finish 2026/02/25 03:45:26 DEBUG : Local file system at /tmp/rclone1483561789/dir2: Waiting for transfers to finish 2026/02/25 03:45:26 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (1.29s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:45:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:45:28 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/25 03:45:28 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:45:28 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:45:42 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/25 03:45:42 INFO : nested/sub dir/file: Copied (new) 2026/02/25 03:45:42 INFO : nested/sub dir/file: Deleted 2026/02/25 03:45:51 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:45:51 INFO : sub dir/hello world: Copied (new) 2026/02/25 03:45:51 INFO : sub dir/hello world: Deleted 2026/02/25 03:45:51 INFO : sub dir: Removing directory 2026/02/25 03:45:51 INFO : nested/sub dir: Removing directory 2026/02/25 03:45:51 INFO : nested: Removing directory 2026/02/25 03:45:51 DEBUG : Local file system at /tmp/rclone1483561789: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (73.88s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:46:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/25 03:46:45 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/25 03:46:45 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:46:45 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:47:21 DEBUG : sub dir/hello world: size = 11 OK 2026/02/25 03:47:21 INFO : sub dir/hello world: Copied (new) 2026/02/25 03:47:21 INFO : sub dir/hello world: Deleted 2026/02/25 03:47:28 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/25 03:47:28 INFO : nested/sub dir/file: Copied (new) 2026/02/25 03:47:28 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (157.08s) === RUN TestMoveWithIgnoreExisting run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:49:27 DEBUG : existing-b: Need to transfer - File not found at Destination 2026/02/25 03:49:27 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/25 03:49:27 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:49:27 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:49:48 DEBUG : existing: size = 6 OK 2026/02/25 03:49:48 INFO : existing: Copied (new) 2026/02/25 03:49:48 INFO : existing: Deleted 2026/02/25 03:49:52 DEBUG : existing-b: size = 6 OK 2026/02/25 03:49:52 INFO : existing-b: Copied (new) 2026/02/25 03:49:52 INFO : existing-b: Deleted 2026/02/25 03:50:06 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 03:50:06 DEBUG : existing: Destination exists, skipping 2026/02/25 03:50:06 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2026/02/25 03:50:06 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 03:50:06 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (82.55s) === RUN TestServerSideMove run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:50:41 DEBUG : Creating backend with remote "TestDrime:rclone-test-vafiwof1hedu" 2026/02/25 03:50:41 DEBUG : Config file has changed externally - reloading sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-zitebes8cofo' -> drime root 'rclone-test-vafiwof1hedu' 2026/02/25 03:52:44 DEBUG : drime root 'rclone-test-vafiwof1hedu': Using server-side directory move 2026/02/25 03:52:44 INFO : drime root 'rclone-test-vafiwof1hedu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/25 03:52:48 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:52:48 DEBUG : empty space: size = 1 OK 2026/02/25 03:52:48 DEBUG : empty space: Sizes identical 2026/02/25 03:52:48 DEBUG : empty space: Unchanged skipping 2026/02/25 03:52:48 DEBUG : potato3: size = 68 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 03:52:48 DEBUG : potato3: size = 60 (drime root 'rclone-test-vafiwof1hedu') 2026/02/25 03:52:48 DEBUG : potato3: Sizes differ 2026/02/25 03:52:48 DEBUG : drime root 'rclone-test-vafiwof1hedu': Waiting for checks to finish 2026/02/25 03:52:52 INFO : potato3: Deleted 2026/02/25 03:52:52 INFO : empty space: Deleted 2026/02/25 03:52:52 DEBUG : drime root 'rclone-test-vafiwof1hedu': Waiting for transfers to finish 2026/02/25 03:52:54 INFO : potato2: Moved (server-side) 2026/02/25 03:52:57 INFO : potato3: Moved (server-side) 2026/02/25 03:53:02 DEBUG : Creating backend with remote "TestDrime:rclone-test-jaxifif0vimu" 2026/02/25 03:53:09 DEBUG : drime root 'rclone-test-jaxifif0vimu': Using server-side directory move 2026/02/25 03:53:16 INFO : drime root 'rclone-test-jaxifif0vimu': Server side directory move succeeded 2026/02/25 03:53:26 ERROR : error listing: directory not found 2026/02/25 03:53:26 DEBUG : drime root 'rclone-test-jaxifif0vimu': Purge remote 2026/02/25 03:53:29 DEBUG : drime root 'rclone-test-vafiwof1hedu': Purge remote 2026/02/25 03:53:31 NOTICE: purge failed: directory not found --- PASS: TestServerSideMove (175.53s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:53:37 DEBUG : Creating backend with remote "TestDrime:rclone-test-fizotuk2muqi" sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-zitebes8cofo' -> drime root 'rclone-test-fizotuk2muqi' 2026/02/25 03:55:04 DEBUG : empty space: Excluded (Size Filter) 2026/02/25 03:55:04 DEBUG : empty space: Excluded 2026/02/25 03:55:04 DEBUG : empty space: Excluded (Size Filter) 2026/02/25 03:55:04 DEBUG : empty space: Excluded 2026/02/25 03:55:04 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:55:04 DEBUG : potato3: size = 68 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 03:55:04 DEBUG : drime root 'rclone-test-fizotuk2muqi': Waiting for checks to finish 2026/02/25 03:55:04 DEBUG : potato3: size = 60 (drime root 'rclone-test-fizotuk2muqi') 2026/02/25 03:55:04 DEBUG : potato3: Sizes differ 2026/02/25 03:55:04 DEBUG : drime root 'rclone-test-fizotuk2muqi': Waiting for transfers to finish 2026/02/25 03:55:05 INFO : potato3: Deleted 2026/02/25 03:55:06 INFO : potato2: Moved (server-side) 2026/02/25 03:55:07 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/25 03:55:07 DEBUG : drime root 'rclone-test-fizotuk2muqi': Purge remote --- FAIL: TestServerSideMoveWithFilter (92.58s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:55:09 DEBUG : Creating backend with remote "TestDrime:rclone-test-fukukuq5zisi" 2026/02/25 03:55:52 INFO : tomatoDir: Making directory sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-zitebes8cofo' -> drime root 'rclone-test-fukukuq5zisi' 2026/02/25 03:56:29 DEBUG : drime root 'rclone-test-fukukuq5zisi': Using server-side directory move 2026/02/25 03:56:29 INFO : drime root 'rclone-test-fukukuq5zisi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/25 03:56:31 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/25 03:56:31 DEBUG : empty space: size = 1 OK 2026/02/25 03:56:31 DEBUG : empty space: Sizes identical 2026/02/25 03:56:31 DEBUG : empty space: Unchanged skipping 2026/02/25 03:56:31 DEBUG : potato3: size = 68 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 03:56:31 DEBUG : potato3: size = 60 (drime root 'rclone-test-fukukuq5zisi') 2026/02/25 03:56:31 DEBUG : potato3: Sizes differ 2026/02/25 03:56:32 DEBUG : drime root 'rclone-test-fukukuq5zisi': Waiting for checks to finish 2026/02/25 03:56:32 INFO : empty space: Deleted 2026/02/25 03:56:32 DEBUG : drime root 'rclone-test-fukukuq5zisi': Waiting for transfers to finish 2026/02/25 03:56:33 INFO : potato3: Deleted 2026/02/25 03:56:34 INFO : potato2: Moved (server-side) 2026/02/25 03:56:35 INFO : potato3: Moved (server-side) 2026/02/25 03:56:35 INFO : tomatoDir: Removing directory 2026/02/25 03:56:38 DEBUG : drime root 'rclone-test-zitebes8cofo': deleted 1 directories 2026/02/25 03:56:44 DEBUG : Creating backend with remote "TestDrime:rclone-test-rolukaq6taxe" 2026/02/25 03:56:48 INFO : tomatoDir: Making directory 2026/02/25 03:56:54 DEBUG : drime root 'rclone-test-rolukaq6taxe': Using server-side directory move 2026/02/25 03:56:58 INFO : drime root 'rclone-test-rolukaq6taxe': Server side directory move succeeded 2026/02/25 03:57:17 ERROR : error listing: directory not found 2026/02/25 03:57:22 ERROR : error listing: directory not found 2026/02/25 03:57:22 DEBUG : drime root 'rclone-test-rolukaq6taxe': Purge remote 2026/02/25 03:57:26 DEBUG : drime root 'rclone-test-fukukuq5zisi': Purge remote 2026/02/25 03:57:30 NOTICE: purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (145.52s) === RUN TestServerSideMoveOverlap run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:1941: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (6.44s) === RUN TestSyncOverlap run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:57:41 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/rclone-sync-test" --- PASS: TestSyncOverlap (35.44s) === RUN TestSyncOverlapWithFilter run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 03:58:16 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/rclone-sync-test" 2026/02/25 03:58:35 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/rclone-sync-test-include/layer2" 2026/02/25 03:58:55 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/rclone-sync-test-ignore-file" 2026/02/25 04:00:17 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/25 04:00:30 DEBUG : rclone-sync-test: Excluded 2026/02/25 04:00:49 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/02/25 04:00:49 DEBUG : drime root 'rclone-test-zitebes8cofo/rclone-sync-test': Waiting for checks to finish 2026/02/25 04:00:49 DEBUG : drime root 'rclone-test-zitebes8cofo/rclone-sync-test': Waiting for transfers to finish 2026/02/25 04:00:49 DEBUG : Waiting for deletions to finish 2026/02/25 04:00:49 INFO : There was nothing to transfer 2026/02/25 04:01:08 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/25 04:01:20 DEBUG : rclone-sync-test: Excluded 2026/02/25 04:01:32 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/02/25 04:01:32 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 04:01:32 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 04:01:32 DEBUG : Waiting for deletions to finish 2026/02/25 04:01:32 INFO : rclone-sync-test-include: Removing directory 2026/02/25 04:01:37 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2026/02/25 04:01:37 DEBUG : drime root 'rclone-test-zitebes8cofo': failed to delete 1 directories 2026/02/25 04:01:37 INFO : There was nothing to transfer 2026/02/25 04:01:49 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/25 04:01:55 DEBUG : rclone-sync-test: Excluded 2026/02/25 04:02:00 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/02/25 04:02:00 DEBUG : drime root 'rclone-test-zitebes8cofo/rclone-sync-test-include/layer2': Waiting for checks to finish 2026/02/25 04:02:00 DEBUG : drime root 'rclone-test-zitebes8cofo/rclone-sync-test-include/layer2': Waiting for transfers to finish 2026/02/25 04:02:00 DEBUG : Waiting for deletions to finish 2026/02/25 04:02:00 INFO : There was nothing to transfer 2026/02/25 04:02:05 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/25 04:02:08 DEBUG : rclone-sync-test: Excluded 2026/02/25 04:02:11 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/02/25 04:02:11 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 04:02:11 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 04:02:11 DEBUG : Waiting for deletions to finish 2026/02/25 04:02:11 INFO : rclone-sync-test-include: Removing directory 2026/02/25 04:02:12 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2026/02/25 04:02:12 DEBUG : drime root 'rclone-test-zitebes8cofo': failed to delete 1 directories 2026/02/25 04:02:12 INFO : There was nothing to transfer 2026/02/25 04:02:15 DEBUG : Excluded 2026/02/25 04:02:17 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/25 04:02:19 DEBUG : rclone-sync-test: Excluded 2026/02/25 04:02:22 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/02/25 04:02:22 DEBUG : drime root 'rclone-test-zitebes8cofo/rclone-sync-test-ignore-file': Waiting for checks to finish 2026/02/25 04:02:22 DEBUG : drime root 'rclone-test-zitebes8cofo/rclone-sync-test-ignore-file': Waiting for transfers to finish 2026/02/25 04:02:22 DEBUG : Waiting for deletions to finish 2026/02/25 04:02:22 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (264.26s) === RUN TestSyncCompareDest run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:02:41 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dst" 2026/02/25 04:02:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/CompareDest" 2026/02/25 04:03:00 DEBUG : one: Need to transfer - File not found at Destination 2026/02/25 04:03:00 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:03:00 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:03:17 DEBUG : one: size = 3 OK 2026/02/25 04:03:17 INFO : one: Copied (new) 2026/02/25 04:03:17 DEBUG : Waiting for deletions to finish 2026/02/25 04:03:26 DEBUG : one: size = 5 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:03:26 DEBUG : one: size = 3 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:03:26 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:03:26 DEBUG : one: Sizes differ 2026/02/25 04:03:29 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:03:38 DEBUG : one: Removing old object on successful upload 2026/02/25 04:03:43 ERROR : one: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/02/25 04:03:43 ERROR : drime root 'rclone-test-zitebes8cofo/dst': not deleting files as there were IO errors 2026/02/25 04:03:43 ERROR : drime root 'rclone-test-zitebes8cofo/dst': not deleting directories as there were IO errors sync_test.go:2132: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2132 Error: Received unexpected error: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." Test: TestSyncCompareDest --- FAIL: TestSyncCompareDest (81.59s) === RUN TestSyncMultipleCompareDest run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:04:58 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dest" 2026/02/25 04:05:14 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/pre-dest1" 2026/02/25 04:05:23 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/pre-dest2" 2026/02/25 04:05:44 DEBUG : 1: size = 1 OK 2026/02/25 04:05:44 DEBUG : 1: Sizes identical 2026/02/25 04:05:44 DEBUG : 1: Destination found in --compare-dest, skipping 2026/02/25 04:05:59 DEBUG : 2: size = 1 OK 2026/02/25 04:05:59 DEBUG : 2: Sizes identical 2026/02/25 04:05:59 DEBUG : 2: Destination found in --compare-dest, skipping 2026/02/25 04:06:07 DEBUG : 3: Need to transfer - File not found at Destination 2026/02/25 04:06:07 DEBUG : drime root 'rclone-test-zitebes8cofo/dest': Waiting for checks to finish 2026/02/25 04:06:07 DEBUG : drime root 'rclone-test-zitebes8cofo/dest': Waiting for transfers to finish 2026/02/25 04:06:34 DEBUG : 3: size = 1 OK 2026/02/25 04:06:34 INFO : 3: Copied (new) 2026/02/25 04:06:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (179.64s) === RUN TestSyncCopyDest run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:07:02 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dst" 2026/02/25 04:07:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/CopyDest" 2026/02/25 04:07:16 DEBUG : one: Need to transfer - File not found at Destination 2026/02/25 04:07:16 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:07:16 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:07:24 DEBUG : one: size = 3 OK 2026/02/25 04:07:24 INFO : one: Copied (new) 2026/02/25 04:07:24 DEBUG : Waiting for deletions to finish 2026/02/25 04:07:29 DEBUG : one: size = 5 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:07:29 DEBUG : one: size = 3 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:07:29 DEBUG : one: Sizes differ 2026/02/25 04:07:29 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:07:29 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:07:43 DEBUG : one: Removing old object on successful upload 2026/02/25 04:07:45 ERROR : one: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/02/25 04:07:45 ERROR : drime root 'rclone-test-zitebes8cofo/dst': not deleting files as there were IO errors 2026/02/25 04:07:45 ERROR : drime root 'rclone-test-zitebes8cofo/dst': not deleting directories as there were IO errors sync_test.go:2298: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2298 Error: Received unexpected error: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." Test: TestSyncCopyDest --- FAIL: TestSyncCopyDest (52.02s) === RUN TestSyncBackupDir run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:08:49 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dst" 2026/02/25 04:08:49 DEBUG : Config file has changed externally - reloading 2026/02/25 04:09:00 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/backup" 2026/02/25 04:09:00 DEBUG : Config file has changed externally - reloading 2026/02/25 04:09:17 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:09:17 DEBUG : one: size = 4 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:09:17 DEBUG : one: size = 3 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:09:17 DEBUG : one: Sizes differ 2026/02/25 04:09:17 DEBUG : two: size = 3 OK 2026/02/25 04:09:17 DEBUG : two: Sizes identical 2026/02/25 04:09:17 DEBUG : two: Unchanged skipping 2026/02/25 04:09:44 INFO : one: Moved (server-side) 2026/02/25 04:09:44 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:09:51 DEBUG : one: size = 4 OK 2026/02/25 04:09:51 INFO : one: Copied (new) 2026/02/25 04:09:51 DEBUG : Waiting for deletions to finish 2026/02/25 04:09:57 INFO : three.txt: Moved (server-side) 2026/02/25 04:09:57 INFO : three.txt: Moved into backup dir 2026/02/25 04:10:24 DEBUG : one: size = 5 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:10:24 DEBUG : one: size = 4 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:10:24 DEBUG : one: Sizes differ 2026/02/25 04:10:24 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:10:24 DEBUG : two: size = 3 OK 2026/02/25 04:10:24 DEBUG : two: Sizes identical 2026/02/25 04:10:24 DEBUG : two: Unchanged skipping 2026/02/25 04:10:27 INFO : one: Deleted 2026/02/25 04:10:31 INFO : one: Moved (server-side) 2026/02/25 04:10:31 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:10:39 DEBUG : one: size = 5 OK 2026/02/25 04:10:39 INFO : one: Copied (new) 2026/02/25 04:10:39 DEBUG : Waiting for deletions to finish 2026/02/25 04:10:40 INFO : three.txt: Deleted 2026/02/25 04:10:42 INFO : three.txt: Moved (server-side) 2026/02/25 04:10:42 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (174.93s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:11:39 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dst" 2026/02/25 04:11:39 DEBUG : Config file has changed externally - reloading 2026/02/25 04:11:50 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/backup" 2026/02/25 04:12:14 DEBUG : one: size = 4 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:12:14 DEBUG : two: size = 3 OK 2026/02/25 04:12:14 DEBUG : one: size = 3 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:12:14 DEBUG : one: Sizes differ 2026/02/25 04:12:14 DEBUG : two: Sizes identical 2026/02/25 04:12:14 DEBUG : two: Unchanged skipping 2026/02/25 04:12:14 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:12:29 INFO : one: Moved (server-side) to: one.bak 2026/02/25 04:12:29 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:12:47 DEBUG : one: size = 4 OK 2026/02/25 04:12:47 INFO : one: Copied (new) 2026/02/25 04:12:47 DEBUG : Waiting for deletions to finish 2026/02/25 04:12:52 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/25 04:12:52 INFO : three.txt: Moved into backup dir 2026/02/25 04:13:09 DEBUG : one: size = 5 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:13:09 DEBUG : one: size = 4 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:13:09 DEBUG : two: size = 3 OK 2026/02/25 04:13:09 DEBUG : two: Sizes identical 2026/02/25 04:13:09 DEBUG : two: Unchanged skipping 2026/02/25 04:13:09 DEBUG : one: Sizes differ 2026/02/25 04:13:09 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:13:12 INFO : one.bak: Deleted 2026/02/25 04:13:16 INFO : one: Moved (server-side) to: one.bak 2026/02/25 04:13:16 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:13:27 DEBUG : one: size = 5 OK 2026/02/25 04:13:27 INFO : one: Copied (new) 2026/02/25 04:13:27 DEBUG : Waiting for deletions to finish 2026/02/25 04:13:30 INFO : three.txt.bak: Deleted 2026/02/25 04:13:32 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/25 04:13:32 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (169.83s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:14:27 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dst" 2026/02/25 04:14:27 DEBUG : Config file has changed externally - reloading 2026/02/25 04:14:38 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/backup" 2026/02/25 04:15:11 DEBUG : one: size = 4 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:15:11 DEBUG : two: size = 3 OK 2026/02/25 04:15:11 DEBUG : two: Sizes identical 2026/02/25 04:15:11 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:15:11 DEBUG : two: Unchanged skipping 2026/02/25 04:15:11 DEBUG : one: size = 3 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:15:11 DEBUG : one: Sizes differ 2026/02/25 04:15:42 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/25 04:15:42 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:16:03 DEBUG : one: size = 4 OK 2026/02/25 04:16:03 INFO : one: Copied (new) 2026/02/25 04:16:03 DEBUG : Waiting for deletions to finish 2026/02/25 04:16:25 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/25 04:16:25 INFO : three.txt: Moved into backup dir 2026/02/25 04:17:50 DEBUG : one: size = 5 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:17:50 DEBUG : one: size = 4 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:17:50 DEBUG : one: Sizes differ 2026/02/25 04:17:50 DEBUG : two: size = 3 OK 2026/02/25 04:17:50 DEBUG : two: Sizes identical 2026/02/25 04:17:50 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:17:50 DEBUG : two: Unchanged skipping 2026/02/25 04:17:59 INFO : one-2019-01-01: Deleted 2026/02/25 04:18:13 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/25 04:18:13 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:18:33 DEBUG : one: size = 5 OK 2026/02/25 04:18:33 INFO : one: Copied (new) 2026/02/25 04:18:33 DEBUG : Waiting for deletions to finish 2026/02/25 04:18:45 INFO : three-2019-01-01.txt: Deleted 2026/02/25 04:19:05 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/25 04:19:05 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (392.64s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:21:28 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dst" 2026/02/25 04:21:28 DEBUG : Config file has changed externally - reloading 2026/02/25 04:22:20 DEBUG : one: size = 4 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:22:20 DEBUG : one: size = 3 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:22:20 DEBUG : one: Sizes differ 2026/02/25 04:22:20 DEBUG : two: size = 3 OK 2026/02/25 04:22:20 DEBUG : two: Sizes identical 2026/02/25 04:22:20 DEBUG : two: Unchanged skipping 2026/02/25 04:22:20 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:22:28 INFO : one: Moved (server-side) to: one.bak 2026/02/25 04:22:28 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:22:41 DEBUG : one: size = 4 OK 2026/02/25 04:22:41 INFO : one: Copied (new) 2026/02/25 04:22:41 DEBUG : Waiting for deletions to finish 2026/02/25 04:22:46 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/25 04:22:46 INFO : three.txt: Moved into backup dir 2026/02/25 04:23:12 DEBUG : three.txt.bak: Excluded (Path Filter) 2026/02/25 04:23:12 DEBUG : three.txt.bak: Excluded 2026/02/25 04:23:12 DEBUG : one.bak: Excluded (Path Filter) 2026/02/25 04:23:12 DEBUG : one.bak: Excluded 2026/02/25 04:23:12 DEBUG : one: size = 5 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:23:12 DEBUG : one: size = 4 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:23:12 DEBUG : two: size = 3 OK 2026/02/25 04:23:12 DEBUG : one: Sizes differ 2026/02/25 04:23:12 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for checks to finish 2026/02/25 04:23:12 DEBUG : two: Sizes identical 2026/02/25 04:23:12 DEBUG : two: Unchanged skipping 2026/02/25 04:23:13 INFO : one.bak: Deleted 2026/02/25 04:23:14 INFO : one: Moved (server-side) to: one.bak 2026/02/25 04:23:14 DEBUG : drime root 'rclone-test-zitebes8cofo/dst': Waiting for transfers to finish 2026/02/25 04:23:29 DEBUG : one: size = 5 OK 2026/02/25 04:23:29 INFO : one: Copied (new) 2026/02/25 04:23:29 DEBUG : Waiting for deletions to finish 2026/02/25 04:23:34 INFO : three.txt.bak: Deleted 2026/02/25 04:23:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/25 04:23:37 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (225.49s) === RUN TestSyncSuffix run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:25:20 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dst" 2026/02/25 04:25:20 DEBUG : Config file has changed externally - reloading 2026/02/25 04:25:36 DEBUG : one: size = 4 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:25:36 DEBUG : one: size = 3 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:25:36 DEBUG : one: Sizes differ 2026/02/25 04:25:47 INFO : one: Moved (server-side) to: one.bak 2026/02/25 04:26:06 DEBUG : one: size = 4 OK 2026/02/25 04:26:06 INFO : one: Copied (new) 2026/02/25 04:26:10 DEBUG : two: size = 3 OK 2026/02/25 04:26:10 DEBUG : two: Sizes identical 2026/02/25 04:26:10 DEBUG : two: Unchanged skipping 2026/02/25 04:26:14 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:26:14 DEBUG : three.txt: size = 5 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:26:14 DEBUG : three.txt: Sizes differ 2026/02/25 04:26:22 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/25 04:26:40 DEBUG : three.txt: size = 6 OK 2026/02/25 04:26:40 INFO : three.txt: Copied (new) 2026/02/25 04:27:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:27:04 DEBUG : one: size = 4 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:27:04 DEBUG : one: Sizes differ 2026/02/25 04:27:11 INFO : one.bak: Deleted 2026/02/25 04:27:14 INFO : one: Moved (server-side) to: one.bak 2026/02/25 04:27:32 DEBUG : one: size = 5 OK 2026/02/25 04:27:32 INFO : one: Copied (new) 2026/02/25 04:27:38 DEBUG : two: size = 3 OK 2026/02/25 04:27:38 DEBUG : two: Sizes identical 2026/02/25 04:27:38 DEBUG : two: Unchanged skipping 2026/02/25 04:27:44 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:27:44 DEBUG : three.txt: size = 6 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:27:44 DEBUG : three.txt: Sizes differ 2026/02/25 04:27:59 INFO : three.txt.bak: Deleted 2026/02/25 04:28:03 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/25 04:28:20 DEBUG : three.txt: size = 19 OK 2026/02/25 04:28:20 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (330.62s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:31:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-zitebes8cofo/dst" 2026/02/25 04:31:35 DEBUG : one: size = 4 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:31:35 DEBUG : one: size = 3 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:31:35 DEBUG : one: Sizes differ 2026/02/25 04:31:44 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/25 04:32:06 DEBUG : one: size = 4 OK 2026/02/25 04:32:06 INFO : one: Copied (new) 2026/02/25 04:32:12 DEBUG : two: size = 3 OK 2026/02/25 04:32:12 DEBUG : two: Sizes identical 2026/02/25 04:32:12 DEBUG : two: Unchanged skipping 2026/02/25 04:32:16 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:32:16 DEBUG : three.txt: size = 5 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:32:16 DEBUG : three.txt: Sizes differ 2026/02/25 04:32:25 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/25 04:32:39 DEBUG : three.txt: size = 6 OK 2026/02/25 04:32:39 INFO : three.txt: Copied (new) 2026/02/25 04:32:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:32:52 DEBUG : one: size = 4 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:32:52 DEBUG : one: Sizes differ 2026/02/25 04:32:57 INFO : one-2019-01-01: Deleted 2026/02/25 04:32:58 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/25 04:33:05 DEBUG : one: size = 5 OK 2026/02/25 04:33:05 INFO : one: Copied (new) 2026/02/25 04:33:06 DEBUG : two: size = 3 OK 2026/02/25 04:33:06 DEBUG : two: Sizes identical 2026/02/25 04:33:06 DEBUG : two: Unchanged skipping 2026/02/25 04:33:07 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:33:07 DEBUG : three.txt: size = 6 (drime root 'rclone-test-zitebes8cofo/dst') 2026/02/25 04:33:07 DEBUG : three.txt: Sizes differ 2026/02/25 04:33:08 INFO : three-2019-01-01.txt: Deleted 2026/02/25 04:33:09 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/25 04:33:16 DEBUG : three.txt: size = 19 OK 2026/02/25 04:33:16 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (242.75s) === RUN TestSyncUTFNorm run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:33:53 DEBUG : Testêé: size = 14 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:33:53 DEBUG : Testêé: size = 18 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 04:33:53 DEBUG : Testêé: Sizes differ 2026/02/25 04:33:53 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 04:33:53 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 04:34:09 DEBUG : Testêé: Removing old object on successful upload 2026/02/25 04:34:12 ERROR : Testêé: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." 2026/02/25 04:34:12 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting files as there were IO errors 2026/02/25 04:34:12 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting directories as there were IO errors sync_test.go:2582: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2582 Error: Received unexpected error: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid." Test: TestSyncUTFNorm --- FAIL: TestSyncUTFNorm (52.25s) === RUN TestSyncImmutable run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:34:29 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/25 04:34:29 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 04:34:29 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 04:34:39 DEBUG : existing: size = 6 OK 2026/02/25 04:34:39 INFO : existing: Copied (new) 2026/02/25 04:34:39 DEBUG : Waiting for deletions to finish 2026/02/25 04:34:50 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 04:34:50 DEBUG : existing: size = 8 (Local file system at /tmp/rclone1483561789) 2026/02/25 04:34:50 DEBUG : existing: size = 6 (drime root 'rclone-test-zitebes8cofo') 2026/02/25 04:34:50 DEBUG : existing: Sizes differ 2026/02/25 04:34:50 ERROR : existing: Source and destination exist but do not match: immutable file modified 2026/02/25 04:34:50 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 04:34:50 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting files as there were IO errors 2026/02/25 04:34:50 ERROR : drime root 'rclone-test-zitebes8cofo': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (46.81s) === RUN TestSyncIgnoreCase run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:35:26 DEBUG : EXISTING: size = 6 OK 2026/02/25 04:35:26 DEBUG : existing: Sizes identical 2026/02/25 04:35:26 DEBUG : existing: Unchanged skipping 2026/02/25 04:35:26 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 04:35:26 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 04:35:26 DEBUG : Waiting for deletions to finish 2026/02/25 04:35:26 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (22.49s) === RUN TestFixCase run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:2666: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (3.93s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local === RUN TestMaxTransfer/Soft run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local --- PASS: TestMaxTransfer (26.77s) --- SKIP: TestMaxTransfer/Hard (9.73s) --- SKIP: TestMaxTransfer/Soft (9.51s) --- SKIP: TestMaxTransfer/Cautious (7.54s) === RUN TestSyncConcurrentDelete run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" 2026/02/25 04:51:13 DEBUG : pacer: low level retry 1/10 (error Get "https://app.drime.cloud/api/v1/drive/file-entries?page=1&parentIds=511553928&perPage=1000": http2: timeout awaiting response headers) 2026/02/25 04:51:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/25 04:51:15 DEBUG : pacer: Reducing sleep to 10ms 2026/02/25 04:53:57 DEBUG : both0: size = 6 OK 2026/02/25 04:53:57 DEBUG : both0: Sizes identical 2026/02/25 04:53:57 DEBUG : both11: size = 6 OK 2026/02/25 04:53:57 DEBUG : both11: Sizes identical 2026/02/25 04:53:57 DEBUG : both12: size = 6 OK 2026/02/25 04:53:57 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for checks to finish 2026/02/25 04:53:57 DEBUG : both12: Sizes identical 2026/02/25 04:53:57 DEBUG : both10: size = 6 OK 2026/02/25 04:53:57 DEBUG : both10: Sizes identical 2026/02/25 04:53:57 DEBUG : both0: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both13: size = 6 OK 2026/02/25 04:53:57 DEBUG : both13: Sizes identical 2026/02/25 04:53:57 DEBUG : both13: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both14: size = 6 OK 2026/02/25 04:53:57 DEBUG : both14: Sizes identical 2026/02/25 04:53:57 DEBUG : both14: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both15: size = 6 OK 2026/02/25 04:53:57 DEBUG : both15: Sizes identical 2026/02/25 04:53:57 DEBUG : both11: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both16: size = 6 OK 2026/02/25 04:53:57 DEBUG : both16: Sizes identical 2026/02/25 04:53:57 DEBUG : both12: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both17: size = 6 OK 2026/02/25 04:53:57 DEBUG : both17: Sizes identical 2026/02/25 04:53:57 DEBUG : both10: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both18: size = 6 OK 2026/02/25 04:53:57 DEBUG : both18: Sizes identical 2026/02/25 04:53:57 DEBUG : both15: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both19: size = 6 OK 2026/02/25 04:53:57 DEBUG : both19: Sizes identical 2026/02/25 04:53:57 DEBUG : both16: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both1: size = 6 OK 2026/02/25 04:53:57 DEBUG : both1: Sizes identical 2026/02/25 04:53:57 DEBUG : both17: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both2: size = 6 OK 2026/02/25 04:53:57 DEBUG : both2: Sizes identical 2026/02/25 04:53:57 DEBUG : both18: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both3: size = 6 OK 2026/02/25 04:53:57 DEBUG : both3: Sizes identical 2026/02/25 04:53:57 DEBUG : both19: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both4: size = 6 OK 2026/02/25 04:53:57 DEBUG : both4: Sizes identical 2026/02/25 04:53:57 DEBUG : both4: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both5: size = 6 OK 2026/02/25 04:53:57 DEBUG : both5: Sizes identical 2026/02/25 04:53:57 DEBUG : both1: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both6: size = 6 OK 2026/02/25 04:53:57 DEBUG : both6: Sizes identical 2026/02/25 04:53:57 DEBUG : both6: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both7: size = 6 OK 2026/02/25 04:53:57 DEBUG : both7: Sizes identical 2026/02/25 04:53:57 DEBUG : both2: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both8: size = 6 OK 2026/02/25 04:53:57 DEBUG : both8: Sizes identical 2026/02/25 04:53:57 DEBUG : both3: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both9: size = 6 OK 2026/02/25 04:53:57 DEBUG : both9: Sizes identical 2026/02/25 04:53:57 DEBUG : both5: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both7: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both8: Unchanged skipping 2026/02/25 04:53:57 DEBUG : both9: Unchanged skipping 2026/02/25 04:53:57 DEBUG : drime root 'rclone-test-zitebes8cofo': Waiting for transfers to finish 2026/02/25 04:53:57 DEBUG : Waiting for deletions to finish 2026/02/25 04:54:04 INFO : only3: Deleted 2026/02/25 04:54:04 INFO : only19: Deleted 2026/02/25 04:54:04 INFO : only15: Deleted 2026/02/25 04:54:04 INFO : only2: Deleted 2026/02/25 04:54:11 INFO : only14: Deleted 2026/02/25 04:54:11 INFO : only9: Deleted 2026/02/25 04:54:11 INFO : only1: Deleted 2026/02/25 04:54:11 INFO : only16: Deleted 2026/02/25 04:54:18 INFO : only5: Deleted 2026/02/25 04:54:18 INFO : only4: Deleted 2026/02/25 04:54:18 INFO : only6: Deleted 2026/02/25 04:54:18 INFO : only18: Deleted 2026/02/25 04:54:24 INFO : only7: Deleted 2026/02/25 04:54:24 INFO : only8: Deleted 2026/02/25 04:54:24 INFO : only0: Deleted 2026/02/25 04:54:24 INFO : only11: Deleted 2026/02/25 04:54:29 INFO : only17: Deleted 2026/02/25 04:54:29 INFO : only10: Deleted 2026/02/25 04:54:29 INFO : only12: Deleted 2026/02/25 04:54:29 INFO : only13: Deleted 2026/02/25 04:54:29 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (1141.22s) === RUN TestSyncConcurrentTruncate run.go:185: Remote "drime root 'rclone-test-zitebes8cofo'", Local "Local file system at /tmp/rclone1483561789", Modify Window "876000h0m0s" panic: test timed out after 2h0m0s running tests: TestSyncConcurrentTruncate (2m1s) goroutine 11538 [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, 3 minutes]: testing.(*T).Run(0xc000483340, {0x29a00db?, 0xc000707ae8?}, 0x2adfa48) /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(0xc000483340) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2477 +0x37 testing.tRunner(0xc000483340, 0xc000707c28) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1934 +0xea testing.runTests(0xc0007e98f0, {0x449a6c0, 0x6c, 0x6c}, {0x8563e0?, 0x44d6f90?, 0x44b2f00?}) /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(0xc000170460) /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(0xc000170460) /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 10 [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 11 [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 11481 [select]: net/http.(*http2ClientConn).roundTrip(0xc000ab8380, 0xc0003f7680, 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(0xc0001705a0, 0xc0003f7680, {0xc0?, 0xcf?}) /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(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:7872 net/http.http2noDialH2RoundTripper.RoundTrip({0xc0006ad860?}, 0xc0003f7680?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:10475 +0x1c net/http.(*Transport).roundTrip(0xc0006ad860, 0xc0003f7680) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:615 +0x473 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(0xc00011bc80, 0xc0003f7680) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x34a net/http.send(0xc0003f7680, {0x2e48160, 0xc00011bc80}, {0xc0009d8a01?, 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(0xc000448c90, 0xc0003f7680, {0x41ecf4?, 0x1a?, 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(0xc000448c90, 0xc0003f7680) /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(0xc0001214f0, {0x2e71680, 0xc000437a70}, 0xc00018fce0) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:355 +0xd87 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc0001214f0, {0x2e71680, 0xc000437a70}, 0xc0009d9388?, {0x0?, 0x0?}, {0x22a83e0, 0xc0007887e0}, 0xc0007f0000?, 0x2ae1190, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:563 +0x40b github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:506 github.com/rclone/rclone/backend/drime.(*Fs).listAll.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:546 +0x87 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0x40?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc00011bce0, 0xc0007a2480, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:228 +0xd2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc00011bce0, 0xc0007a2480) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:247 +0x92 github.com/rclone/rclone/backend/drime.(*Fs).listAll(0xc000170500, {0x2e71680, 0xc000437a70}, {0xc000596660, 0x9}, 0x0, 0x1, {0x762de8454c00?, 0x762e2fce25c0?}, 0xc0009d9800) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:545 +0x685 github.com/rclone/rclone/backend/drime.(*Fs).readMetaDataForPath(0xc000170500, {0x2e71680, 0xc000437a70}, {0xc00068b5a5?, 0x481001?}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:302 +0x11e github.com/rclone/rclone/backend/drime.(*Object).readMetaData(0xc000de6300, {0x2e71680?, 0xc000437a70?}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:1451 +0x30 github.com/rclone/rclone/backend/drime.(*Fs).newObjectWithInfo(0xc000170500, {0x2e71680, 0xc000437a70}, {0xc00068b5a5, 0x5}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:447 +0x9c github.com/rclone/rclone/backend/drime.(*Fs).NewObject(...) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:458 github.com/rclone/rclone/backend/drime.(*Fs).Put(0xc000170500, {0x2e71680, 0xc000437a70}, {0x2e46520, 0xc0002464b0}, {0x2e7da50, 0xc000451880}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:654 +0x79 github.com/rclone/rclone/fstest.(*Run).WriteObjectTo(0xc0003e9ab0, {0x2e71680, 0xc000437a70}, {0x2e88a70, 0xc000170500}, {0xc00068b5a5, 0x5}, {0x295ea56, 0x6}, {0x1dcd64ff, ...}, ...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:280 +0x3f2 github.com/rclone/rclone/fstest.(*Run).WriteObject(...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297 github.com/rclone/rclone/fs/sync.testSyncConcurrent(0xc000ab81c0, {0x2965688, 0x8}) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2768 +0x805 github.com/rclone/rclone/fs/sync.TestSyncConcurrentTruncate(0xc000ab81c0?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793 +0x1f testing.tRunner(0xc000ab81c0, 0x2adfa48) /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 10843 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0005921e0, {0x2e716b8, 0xc0004ac230}) /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 10819 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:389 +0x11d goroutine 11197 [IO wait]: internal/poll.runtime_pollWait(0x762e2fc5ba00, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000122500?, 0xc00015b300?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000122500, {0xc00015b300, 0x1300, 0x1300}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x279 net.(*netFD).Read(0xc000122500, {0xc00015b300?, 0xc45?, 0xc000412360?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/fd_posix.go:68 +0x25 net.(*conn).Read(0xc000088000, {0xc00015b300?, 0xc00015b9b6?, 0x5?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/net.go:196 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000260090, {0xc00015b300?, 0x762de845a7c0?, 0x762e2fce25c0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0007e8870, {0xc00015b300?, 0xc45?, 0x3?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:816 +0x3b bytes.(*Buffer).ReadFrom(0xc0005e02a8, {0x2e49a80, 0xc0007e8870}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bytes/buffer.go:217 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0005e0008, {0x762de843c220, 0xc000260090}, 0x448c34?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:838 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0005e0008, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:627 +0x3db 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(0xc0005e0008, {0xc000421000, 0x1000, 0x7d1648?}) /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(0xc0009dbb00, {0xc0000002e0, 0x9, 0xc0007de030?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bufio/bufio.go:245 +0x197 io.ReadAtLeast({0x2e46a80, 0xc0009dbb00}, {0xc0000002e0, 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({0xc0000002e0, 0x9, 0x7eea7f?}, {0x2e46a80?, 0xc0009dbb00?}) /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(0xc0000002a0) /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(0xc000095fa8) /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(0xc000ab8380) /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 11196 /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 11473 [select]: net/http.(*http2clientStream).writeRequest(0xc0007de180, 0xc0003f7680, 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(0xc0007de180, 0xc000680c60?, 0xc00074f740?) /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 11481 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8616 +0x485 goroutine 2533 [chan receive, 100 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 2503 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 "./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Finished ERROR in 2h0m7.21507901s (try 1/5): exit status 2: Failed [TestCopyOverSelf TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf TestSyncBasedOnCheckSum TestSyncSizeOnly TestSyncIgnoreSize TestSyncIgnoreTimes TestSyncAfterChangingFilesSizeOnly TestSyncAfterChangingContentsOnly TestServerSideMoveWithFilter TestSyncCompareDest TestSyncCopyDest TestSyncUTFNorm]