"./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCaseSensitivity|TestDirCreate|TestDirFileOpen|TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMetadataExtension|TestDirMethods|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleMethodsWrite|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestRWFileHandleWriteAt|TestRWFileHandleWriteNoWrite|TestRWFileModTimeWithOpenWriters|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleRelease|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters|TestZipDirsInRoot|TestZipLargeFiles|TestZipManyFiles|TestZipManySubDirs)$|^TestFileRename$/^(full,forceCache=false|minimal,forceCache=false|minimal,forceCache=true|off,forceCache=false|writes,forceCache=false|writes,forceCache=true)$|^TestFileSetModTime$/^(cache=full,open=false,write=false|cache=full,open=true,write=false|cache=full,open=true,write=true|cache=off,open=false,write=false|cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Starting (try 5/5) 2026/04/18 05:24:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-nelezit5dace" 2026/04/18 05:24:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/04/18 05:24:19 DEBUG : Creating backend with remote "/tmp/rclone1297062720" === RUN TestDirHandleMethods run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:19 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:20 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:20 DEBUG : Looking for writers 2026/04/18 05:24:20 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleMethods (2.98s) === RUN TestDirHandleReaddir run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:22 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:23 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:23 DEBUG : Looking for writers 2026/04/18 05:24:23 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddir (2.40s) === RUN TestDirHandleReaddirnames run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:24 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:25 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:25 DEBUG : Looking for writers 2026/04/18 05:24:25 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddirnames (2.29s) === RUN TestDirMethods run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:26 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:27 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:27 DEBUG : Looking for writers 2026/04/18 05:24:27 DEBUG : >WaitForWriters: --- FAIL: TestDirMethods (2.37s) === RUN TestDirForgetAll run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:29 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:30 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:30 DEBUG : Looking for writers 2026/04/18 05:24:30 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetAll (2.41s) === RUN TestDirForgetPath run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:31 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:32 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:32 DEBUG : Looking for writers 2026/04/18 05:24:32 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetPath (2.41s) === RUN TestDirWalk run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:34 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:34 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:34 DEBUG : Looking for writers 2026/04/18 05:24:34 DEBUG : >WaitForWriters: --- FAIL: TestDirWalk (2.32s) === RUN TestDirSetModTime run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:36 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:37 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:37 DEBUG : Looking for writers 2026/04/18 05:24:37 DEBUG : >WaitForWriters: --- FAIL: TestDirSetModTime (2.34s) === RUN TestDirStat run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:38 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:39 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:39 DEBUG : Looking for writers 2026/04/18 05:24:39 DEBUG : >WaitForWriters: --- FAIL: TestDirStat (2.75s) === RUN TestDirReadDirAll run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:41 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:42 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:42 DEBUG : Looking for writers 2026/04/18 05:24:42 DEBUG : >WaitForWriters: --- FAIL: TestDirReadDirAll (2.35s) === RUN TestDirOpen run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:43 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:44 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:44 DEBUG : Looking for writers 2026/04/18 05:24:44 DEBUG : >WaitForWriters: --- FAIL: TestDirOpen (2.43s) === RUN TestDirCreate run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:46 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:47 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:47 DEBUG : Looking for writers 2026/04/18 05:24:47 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (2.48s) === RUN TestDirMkdir run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:48 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:49 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:49 DEBUG : Looking for writers 2026/04/18 05:24:49 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdir (2.32s) === RUN TestDirMkdirSub run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:51 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:51 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:51 DEBUG : Looking for writers 2026/04/18 05:24:51 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdirSub (2.39s) === RUN TestDirRemove run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:53 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:54 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:54 DEBUG : Looking for writers 2026/04/18 05:24:54 DEBUG : >WaitForWriters: --- FAIL: TestDirRemove (2.40s) === RUN TestDirRemoveAll run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:55 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:56 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:56 DEBUG : Looking for writers 2026/04/18 05:24:56 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveAll (2.42s) === RUN TestDirRemoveName run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:24:58 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:24:59 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:24:59 DEBUG : Looking for writers 2026/04/18 05:24:59 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (2.38s) === RUN TestDirRename run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:00 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:01 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:01 DEBUG : Looking for writers 2026/04/18 05:25:01 DEBUG : >WaitForWriters: --- FAIL: TestDirRename (2.34s) === RUN TestDirFileOpen run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:03 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:03 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:03 DEBUG : Looking for writers 2026/04/18 05:25:03 DEBUG : >WaitForWriters: --- FAIL: TestDirFileOpen (2.36s) === RUN TestDirMetadataExtension run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:05 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:06 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:06 DEBUG : Looking for writers 2026/04/18 05:25:06 DEBUG : >WaitForWriters: --- FAIL: TestDirMetadataExtension (2.49s) === RUN TestFileMethods run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:07 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:08 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:08 DEBUG : Looking for writers 2026/04/18 05:25:08 DEBUG : >WaitForWriters: --- FAIL: TestFileMethods (2.47s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:10 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:11 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:11 DEBUG : Looking for writers 2026/04/18 05:25:11 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:12 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:13 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:13 DEBUG : Looking for writers 2026/04/18 05:25:13 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:15 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:16 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:16 DEBUG : Looking for writers 2026/04/18 05:25:16 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:17 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:25:17 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:25:17 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:17 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:17 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:17 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:17 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:18 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:18 DEBUG : Looking for writers 2026/04/18 05:25:18 DEBUG : >WaitForWriters: 2026/04/18 05:25:18 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:19 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:25:19 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:25:19 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:19 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:19 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:19 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:19 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:19 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:19 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:19 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:19 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:20 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:20 DEBUG : Looking for writers 2026/04/18 05:25:20 DEBUG : >WaitForWriters: 2026/04/18 05:25:20 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:22 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:25:22 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:25:22 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:22 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:22 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:22 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:22 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:23 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:23 DEBUG : Looking for writers 2026/04/18 05:25:23 DEBUG : >WaitForWriters: 2026/04/18 05:25:23 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestFileSetModTime (14.32s) --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (2.47s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=false (2.29s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (2.34s) --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (2.46s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (2.35s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (2.41s) === RUN TestFileOpenRead run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:24 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:25 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:25 DEBUG : Looking for writers 2026/04/18 05:25:25 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenRead (2.43s) === RUN TestFileOpenWrite run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:27 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:27 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:27 DEBUG : Looking for writers 2026/04/18 05:25:27 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:290 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:27 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:232 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestFileOpenWrite Messages: directories --- FAIL: TestFileOpenWrite (10.04s) === RUN TestFileRemove run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:37 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:38 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:38 DEBUG : Looking for writers 2026/04/18 05:25:38 DEBUG : >WaitForWriters: --- FAIL: TestFileRemove (2.51s) === RUN TestFileRemoveAll run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:39 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:40 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:40 DEBUG : Looking for writers 2026/04/18 05:25:40 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:290 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:27 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:264 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestFileRemoveAll Messages: directories --- FAIL: TestFileRemoveAll (10.09s) === RUN TestFileOpen run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:49 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:51 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:51 DEBUG : Looking for writers 2026/04/18 05:25:51 DEBUG : >WaitForWriters: --- FAIL: TestFileOpen (2.95s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:52 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:53 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:53 DEBUG : Looking for writers 2026/04/18 05:25:53 DEBUG : >WaitForWriters: === RUN TestFileRename/minimal,forceCache=false run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:55 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:25:55 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:25:55 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:55 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:55 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:55 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:55 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:55 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:55 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:55 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:55 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:55 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:55 DEBUG : Looking for writers 2026/04/18 05:25:55 DEBUG : >WaitForWriters: 2026/04/18 05:25:55 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting === RUN TestFileRename/minimal,forceCache=true run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:57 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:25:57 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:25:57 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:57 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:57 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:57 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:57 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:57 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:57 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:57 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:57 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:25:58 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:25:58 DEBUG : Looking for writers 2026/04/18 05:25:58 DEBUG : >WaitForWriters: 2026/04/18 05:25:58 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=false run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:25:59 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:25:59 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:25:59 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:59 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:59 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:59 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:59 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:59 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:59 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:25:59 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:25:59 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:00 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:00 DEBUG : Looking for writers 2026/04/18 05:26:00 DEBUG : >WaitForWriters: 2026/04/18 05:26:00 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=true run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:02 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:26:02 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:26:02 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:02 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:02 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:02 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:02 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:02 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:02 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:02 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:02 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:03 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:03 DEBUG : Looking for writers 2026/04/18 05:26:03 DEBUG : >WaitForWriters: 2026/04/18 05:26:03 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting === RUN TestFileRename/full,forceCache=false run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:04 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:26:04 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:26:04 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:04 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:04 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:04 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:04 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:04 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:04 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:04 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:04 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:05 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:05 DEBUG : Looking for writers 2026/04/18 05:26:05 DEBUG : >WaitForWriters: 2026/04/18 05:26:05 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestFileRename (14.76s) --- FAIL: TestFileRename/off,forceCache=false (2.34s) --- FAIL: TestFileRename/minimal,forceCache=false (2.37s) --- FAIL: TestFileRename/minimal,forceCache=true (2.43s) --- FAIL: TestFileRename/writes,forceCache=false (2.61s) --- FAIL: TestFileRename/writes,forceCache=true (2.48s) --- FAIL: TestFileRename/full,forceCache=false (2.54s) === RUN TestReadFileHandleMethods run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:07 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:08 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:08 DEBUG : Looking for writers 2026/04/18 05:26:08 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (2.43s) === RUN TestReadFileHandleSeek run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:09 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:10 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:10 DEBUG : Looking for writers 2026/04/18 05:26:10 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleSeek (2.48s) === RUN TestReadFileHandleReadAt run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:12 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:13 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:13 DEBUG : Looking for writers 2026/04/18 05:26:13 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (2.56s) === RUN TestReadFileHandleFlush run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:14 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:15 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:15 DEBUG : Looking for writers 2026/04/18 05:26:15 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:290 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:18 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:182 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestReadFileHandleFlush Messages: directories --- FAIL: TestReadFileHandleFlush (10.05s) === RUN TestReadFileHandleRelease run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:25 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:26 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:26 DEBUG : Looking for writers 2026/04/18 05:26:26 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (2.61s) === RUN TestRWFileHandleMethodsRead run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:27 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:26:27 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:26:27 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:27 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:27 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:28 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:28 DEBUG : Looking for writers 2026/04/18 05:26:28 DEBUG : >WaitForWriters: 2026/04/18 05:26:28 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (2.48s) === RUN TestRWFileHandleSeek run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:30 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:26:30 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:26:30 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:30 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:30 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:30 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:30 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:30 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:30 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:30 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:30 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:30 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:30 DEBUG : Looking for writers 2026/04/18 05:26:30 DEBUG : >WaitForWriters: 2026/04/18 05:26:30 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (2.42s) === RUN TestRWFileHandleReadAt run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:32 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:26:32 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:26:32 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:32 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:32 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:32 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:32 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:32 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:32 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:32 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:32 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:33 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:33 DEBUG : Looking for writers 2026/04/18 05:26:33 DEBUG : >WaitForWriters: 2026/04/18 05:26:33 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (2.44s) === RUN TestRWFileHandleFlushRead run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:34 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:26:34 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:26:34 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:34 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:34 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:35 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:35 DEBUG : Looking for writers 2026/04/18 05:26:35 DEBUG : >WaitForWriters: 2026/04/18 05:26:35 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleFlushRead (2.65s) === RUN TestRWFileHandleReleaseRead run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:37 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:26:37 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:26:37 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:37 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:37 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:37 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:37 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:37 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:37 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:37 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:37 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:38 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:38 DEBUG : Looking for writers 2026/04/18 05:26:38 DEBUG : >WaitForWriters: 2026/04/18 05:26:38 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (2.49s) === RUN TestRWFileHandleMethodsWrite run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:26:40 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:26:40 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:26:40 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:40 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:40 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:40 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:40 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:40 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:40 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:26:40 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:26:40 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:26:40 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/18 05:26:40 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:26:40 DEBUG : file1: newRWFileHandle: 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): openPending: 2026/04/18 05:26:40 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/18 05:26:40 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): >openPending: err= 2026/04/18 05:26:40 DEBUG : file1: >newRWFileHandle: err= 2026/04/18 05:26:40 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:26:40 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/18 05:26:40 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): _writeAt: size=5, off=0 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): >_writeAt: n=5, err= 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): _writeAt: size=7, off=5 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): >_writeAt: n=7, err= 2026/04/18 05:26:40 DEBUG : file1: vfs cache: truncate to size=11 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): close: 2026/04/18 05:26:40 DEBUG : file1: vfs cache: setting modification time to 2026-04-18 05:26:40.359725394 +0000 UTC m=+142.093331156 2026/04/18 05:26:40 INFO : file1: vfs cache: queuing for upload in 100ms 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): >close: err= 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): close: 2026/04/18 05:26:40 DEBUG : file1(0x32538df4c80): >close: err=file already closed 2026/04/18 05:26:40 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:26:40 DEBUG : Looking for writers 2026/04/18 05:26:40 DEBUG : file1: reading active writers 2026/04/18 05:26:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/18 05:26:40 DEBUG : Looking for writers 2026/04/18 05:26:40 DEBUG : file1: reading active writers 2026/04/18 05:26:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/18 05:26:40 DEBUG : Looking for writers 2026/04/18 05:26:40 DEBUG : file1: reading active writers 2026/04/18 05:26:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/18 05:26:40 DEBUG : Looking for writers 2026/04/18 05:26:40 DEBUG : file1: reading active writers 2026/04/18 05:26:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/18 05:26:40 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:26:40 DEBUG : Looking for writers 2026/04/18 05:26:40 DEBUG : file1: reading active writers 2026/04/18 05:26:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/18 05:26:40 DEBUG : Looking for writers 2026/04/18 05:26:40 DEBUG : file1: reading active writers 2026/04/18 05:26:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/18 05:26:40 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:40 ERROR : file1: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:40 DEBUG : Looking for writers 2026/04/18 05:26:40 DEBUG : file1: reading active writers 2026/04/18 05:26:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/18 05:26:41 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:26:41 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:41 ERROR : file1: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:41 DEBUG : Looking for writers 2026/04/18 05:26:41 DEBUG : file1: reading active writers 2026/04/18 05:26:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:41 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:26:42 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:42 ERROR : file1: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:42 DEBUG : Looking for writers 2026/04/18 05:26:42 DEBUG : file1: reading active writers 2026/04/18 05:26:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:43 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:26:43 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:43 ERROR : file1: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:43 DEBUG : Looking for writers 2026/04/18 05:26:43 DEBUG : file1: reading active writers 2026/04/18 05:26:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:44 DEBUG : Looking for writers 2026/04/18 05:26:44 DEBUG : file1: reading active writers 2026/04/18 05:26:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:45 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:26:45 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:45 ERROR : file1: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:45 DEBUG : Looking for writers 2026/04/18 05:26:45 DEBUG : file1: reading active writers 2026/04/18 05:26:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:46 DEBUG : Looking for writers 2026/04/18 05:26:46 DEBUG : file1: reading active writers 2026/04/18 05:26:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:47 DEBUG : Looking for writers 2026/04/18 05:26:47 DEBUG : file1: reading active writers 2026/04/18 05:26:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:48 DEBUG : Looking for writers 2026/04/18 05:26:48 DEBUG : file1: reading active writers 2026/04/18 05:26:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:48 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:26:49 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:49 ERROR : file1: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:49 DEBUG : Looking for writers 2026/04/18 05:26:49 DEBUG : file1: reading active writers 2026/04/18 05:26:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:50 DEBUG : Looking for writers 2026/04/18 05:26:50 DEBUG : file1: reading active writers 2026/04/18 05:26:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:51 DEBUG : Looking for writers 2026/04/18 05:26:51 DEBUG : file1: reading active writers 2026/04/18 05:26:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:52 DEBUG : Looking for writers 2026/04/18 05:26:52 DEBUG : file1: reading active writers 2026/04/18 05:26:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:53 DEBUG : Looking for writers 2026/04/18 05:26:53 DEBUG : file1: reading active writers 2026/04/18 05:26:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:54 DEBUG : Looking for writers 2026/04/18 05:26:54 DEBUG : file1: reading active writers 2026/04/18 05:26:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:55 DEBUG : Looking for writers 2026/04/18 05:26:55 DEBUG : file1: reading active writers 2026/04/18 05:26:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:55 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:26:56 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:56 ERROR : file1: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:26:56 DEBUG : Looking for writers 2026/04/18 05:26:56 DEBUG : file1: reading active writers 2026/04/18 05:26:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:57 DEBUG : Looking for writers 2026/04/18 05:26:57 DEBUG : file1: reading active writers 2026/04/18 05:26:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:58 DEBUG : Looking for writers 2026/04/18 05:26:58 DEBUG : file1: reading active writers 2026/04/18 05:26:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:26:59 DEBUG : Looking for writers 2026/04/18 05:26:59 DEBUG : file1: reading active writers 2026/04/18 05:26:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:00 DEBUG : Looking for writers 2026/04/18 05:27:00 DEBUG : file1: reading active writers 2026/04/18 05:27:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:01 DEBUG : Looking for writers 2026/04/18 05:27:01 DEBUG : file1: reading active writers 2026/04/18 05:27:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:02 DEBUG : Looking for writers 2026/04/18 05:27:02 DEBUG : file1: reading active writers 2026/04/18 05:27:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:03 DEBUG : Looking for writers 2026/04/18 05:27:03 DEBUG : file1: reading active writers 2026/04/18 05:27:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:04 DEBUG : Looking for writers 2026/04/18 05:27:04 DEBUG : file1: reading active writers 2026/04/18 05:27:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:05 DEBUG : Looking for writers 2026/04/18 05:27:05 DEBUG : file1: reading active writers 2026/04/18 05:27:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:06 DEBUG : Looking for writers 2026/04/18 05:27:06 DEBUG : file1: reading active writers 2026/04/18 05:27:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:07 DEBUG : Looking for writers 2026/04/18 05:27:07 DEBUG : file1: reading active writers 2026/04/18 05:27:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:08 DEBUG : Looking for writers 2026/04/18 05:27:08 DEBUG : file1: reading active writers 2026/04/18 05:27:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:09 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:27:09 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:09 ERROR : file1: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:09 DEBUG : Looking for writers 2026/04/18 05:27:09 DEBUG : file1: reading active writers 2026/04/18 05:27:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:10 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x3253987e200 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x32539314008 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458408202304} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14010948459745573202 ext:142093331156 loc:0x46a1880} ATime:{wall:14010948459745596387 ext:142093354320 loc:0x46a1880} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/18 05:27:10 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:337 Error: Should be true Test: TestRWFileHandleMethodsWrite Messages: listing wrong, want file1 (11) got fstest.go:203: Not found "file1" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:337 Error: Not equal: expected: 0 actual : 1 Test: TestRWFileHandleMethodsWrite Messages: 1 objects not found 2026/04/18 05:27:18 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:27:18 DEBUG : Looking for writers 2026/04/18 05:27:18 DEBUG : file1: reading active writers 2026/04/18 05:27:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/18 05:27:18 DEBUG : Looking for writers 2026/04/18 05:27:18 DEBUG : file1: reading active writers 2026/04/18 05:27:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/18 05:27:18 DEBUG : Looking for writers 2026/04/18 05:27:18 DEBUG : file1: reading active writers 2026/04/18 05:27:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/18 05:27:18 DEBUG : Looking for writers 2026/04/18 05:27:18 DEBUG : file1: reading active writers 2026/04/18 05:27:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/18 05:27:18 DEBUG : Looking for writers 2026/04/18 05:27:18 DEBUG : file1: reading active writers 2026/04/18 05:27:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/18 05:27:19 DEBUG : Looking for writers 2026/04/18 05:27:19 DEBUG : file1: reading active writers 2026/04/18 05:27:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/18 05:27:19 DEBUG : Looking for writers 2026/04/18 05:27:19 DEBUG : file1: reading active writers 2026/04/18 05:27:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/18 05:27:20 DEBUG : Looking for writers 2026/04/18 05:27:20 DEBUG : file1: reading active writers 2026/04/18 05:27:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:21 DEBUG : Looking for writers 2026/04/18 05:27:21 DEBUG : file1: reading active writers 2026/04/18 05:27:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:22 DEBUG : Looking for writers 2026/04/18 05:27:22 DEBUG : file1: reading active writers 2026/04/18 05:27:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:23 DEBUG : Looking for writers 2026/04/18 05:27:23 DEBUG : file1: reading active writers 2026/04/18 05:27:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:24 DEBUG : Looking for writers 2026/04/18 05:27:24 DEBUG : file1: reading active writers 2026/04/18 05:27:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:25 DEBUG : Looking for writers 2026/04/18 05:27:25 DEBUG : file1: reading active writers 2026/04/18 05:27:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:26 DEBUG : Looking for writers 2026/04/18 05:27:26 DEBUG : file1: reading active writers 2026/04/18 05:27:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:27 DEBUG : Looking for writers 2026/04/18 05:27:27 DEBUG : file1: reading active writers 2026/04/18 05:27:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:28 DEBUG : Looking for writers 2026/04/18 05:27:28 DEBUG : file1: reading active writers 2026/04/18 05:27:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:29 DEBUG : Looking for writers 2026/04/18 05:27:29 DEBUG : file1: reading active writers 2026/04/18 05:27:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:30 DEBUG : Looking for writers 2026/04/18 05:27:30 DEBUG : file1: reading active writers 2026/04/18 05:27:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:31 DEBUG : Looking for writers 2026/04/18 05:27:31 DEBUG : file1: reading active writers 2026/04/18 05:27:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:32 DEBUG : Looking for writers 2026/04/18 05:27:32 DEBUG : file1: reading active writers 2026/04/18 05:27:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:33 DEBUG : Looking for writers 2026/04/18 05:27:33 DEBUG : file1: reading active writers 2026/04/18 05:27:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:34 DEBUG : Looking for writers 2026/04/18 05:27:34 DEBUG : file1: reading active writers 2026/04/18 05:27:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:35 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:27:35 DEBUG : Looking for writers 2026/04/18 05:27:35 DEBUG : file1: reading active writers 2026/04/18 05:27:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:35 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:35 ERROR : file1: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:36 DEBUG : Looking for writers 2026/04/18 05:27:36 DEBUG : file1: reading active writers 2026/04/18 05:27:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:37 DEBUG : Looking for writers 2026/04/18 05:27:37 DEBUG : file1: reading active writers 2026/04/18 05:27:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:38 DEBUG : Looking for writers 2026/04/18 05:27:38 DEBUG : file1: reading active writers 2026/04/18 05:27:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:39 DEBUG : Looking for writers 2026/04/18 05:27:39 DEBUG : file1: reading active writers 2026/04/18 05:27:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:40 DEBUG : Looking for writers 2026/04/18 05:27:40 DEBUG : file1: reading active writers 2026/04/18 05:27:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:40 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2026/04/18 05:27:40 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11) 2026/04/18 05:27:41 DEBUG : Looking for writers 2026/04/18 05:27:41 DEBUG : file1: reading active writers 2026/04/18 05:27:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:42 DEBUG : Looking for writers 2026/04/18 05:27:42 DEBUG : file1: reading active writers 2026/04/18 05:27:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:43 DEBUG : Looking for writers 2026/04/18 05:27:43 DEBUG : file1: reading active writers 2026/04/18 05:27:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:44 DEBUG : Looking for writers 2026/04/18 05:27:44 DEBUG : file1: reading active writers 2026/04/18 05:27:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:45 DEBUG : Looking for writers 2026/04/18 05:27:45 DEBUG : file1: reading active writers 2026/04/18 05:27:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:46 DEBUG : Looking for writers 2026/04/18 05:27:46 DEBUG : file1: reading active writers 2026/04/18 05:27:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:47 DEBUG : Looking for writers 2026/04/18 05:27:47 DEBUG : file1: reading active writers 2026/04/18 05:27:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:48 DEBUG : Looking for writers 2026/04/18 05:27:48 DEBUG : file1: reading active writers 2026/04/18 05:27:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:48 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x3253987e200 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x32539314008 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458408202304} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14010948459745573202 ext:142093331156 loc:0x46a1880} ATime:{wall:14010948459745596387 ext:142093354320 loc:0x46a1880} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/18 05:27:48 DEBUG : >WaitForWriters: 2026/04/18 05:27:48 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsWrite (69.02s) === RUN TestRWFileHandleWriteAt run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:27:49 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:27:49 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:27:49 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:27:49 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:27:49 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:27:49 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:27:49 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:27:49 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:27:49 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:27:49 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:27:49 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:27:49 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/18 05:27:49 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:27:49 DEBUG : file1: newRWFileHandle: 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): openPending: 2026/04/18 05:27:49 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/18 05:27:49 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): >openPending: err= 2026/04/18 05:27:49 DEBUG : file1: >newRWFileHandle: err= 2026/04/18 05:27:49 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:27:49 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/18 05:27:49 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): _writeAt: size=7, off=0 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): >_writeAt: n=7, err= 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): _writeAt: size=6, off=5 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): >_writeAt: n=6, err= 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): close: 2026/04/18 05:27:49 DEBUG : file1: vfs cache: setting modification time to 2026-04-18 05:27:49.3843109 +0000 UTC m=+211.117916652 2026/04/18 05:27:49 INFO : file1: vfs cache: queuing for upload in 100ms 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): >close: err= 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): _writeAt: size=5, off=0 2026/04/18 05:27:49 DEBUG : file1(0x32538e9fd80): >_writeAt: n=0, err=file already closed 2026/04/18 05:27:49 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:27:49 DEBUG : Looking for writers 2026/04/18 05:27:49 DEBUG : file1: reading active writers 2026/04/18 05:27:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/18 05:27:49 DEBUG : Looking for writers 2026/04/18 05:27:49 DEBUG : file1: reading active writers 2026/04/18 05:27:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/18 05:27:49 DEBUG : Looking for writers 2026/04/18 05:27:49 DEBUG : file1: reading active writers 2026/04/18 05:27:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/18 05:27:49 DEBUG : Looking for writers 2026/04/18 05:27:49 DEBUG : file1: reading active writers 2026/04/18 05:27:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/18 05:27:49 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:27:49 DEBUG : Looking for writers 2026/04/18 05:27:49 DEBUG : file1: reading active writers 2026/04/18 05:27:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/18 05:27:49 DEBUG : Looking for writers 2026/04/18 05:27:49 DEBUG : file1: reading active writers 2026/04/18 05:27:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/18 05:27:49 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:49 ERROR : file1: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:50 DEBUG : Looking for writers 2026/04/18 05:27:50 DEBUG : file1: reading active writers 2026/04/18 05:27:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/18 05:27:50 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:27:50 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:50 ERROR : file1: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:50 DEBUG : Looking for writers 2026/04/18 05:27:50 DEBUG : file1: reading active writers 2026/04/18 05:27:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:50 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:27:51 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:51 ERROR : file1: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:51 DEBUG : Looking for writers 2026/04/18 05:27:51 DEBUG : file1: reading active writers 2026/04/18 05:27:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:52 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:27:52 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:52 ERROR : file1: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:52 DEBUG : Looking for writers 2026/04/18 05:27:52 DEBUG : file1: reading active writers 2026/04/18 05:27:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:53 DEBUG : Looking for writers 2026/04/18 05:27:53 DEBUG : file1: reading active writers 2026/04/18 05:27:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:54 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:27:54 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:54 ERROR : file1: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:54 DEBUG : Looking for writers 2026/04/18 05:27:54 DEBUG : file1: reading active writers 2026/04/18 05:27:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:55 DEBUG : Looking for writers 2026/04/18 05:27:55 DEBUG : file1: reading active writers 2026/04/18 05:27:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:56 DEBUG : Looking for writers 2026/04/18 05:27:56 DEBUG : file1: reading active writers 2026/04/18 05:27:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:57 DEBUG : Looking for writers 2026/04/18 05:27:57 DEBUG : file1: reading active writers 2026/04/18 05:27:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:57 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:27:58 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:58 ERROR : file1: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:27:58 DEBUG : Looking for writers 2026/04/18 05:27:58 DEBUG : file1: reading active writers 2026/04/18 05:27:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:27:59 DEBUG : Looking for writers 2026/04/18 05:27:59 DEBUG : file1: reading active writers 2026/04/18 05:27:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:00 DEBUG : Looking for writers 2026/04/18 05:28:00 DEBUG : file1: reading active writers 2026/04/18 05:28:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:01 DEBUG : Looking for writers 2026/04/18 05:28:01 DEBUG : file1: reading active writers 2026/04/18 05:28:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:02 DEBUG : Looking for writers 2026/04/18 05:28:02 DEBUG : file1: reading active writers 2026/04/18 05:28:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:03 DEBUG : Looking for writers 2026/04/18 05:28:03 DEBUG : file1: reading active writers 2026/04/18 05:28:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:04 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:28:04 DEBUG : Looking for writers 2026/04/18 05:28:04 DEBUG : file1: reading active writers 2026/04/18 05:28:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:04 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:04 ERROR : file1: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:05 DEBUG : Looking for writers 2026/04/18 05:28:05 DEBUG : file1: reading active writers 2026/04/18 05:28:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:06 DEBUG : Looking for writers 2026/04/18 05:28:06 DEBUG : file1: reading active writers 2026/04/18 05:28:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:07 DEBUG : Looking for writers 2026/04/18 05:28:07 DEBUG : file1: reading active writers 2026/04/18 05:28:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:08 DEBUG : Looking for writers 2026/04/18 05:28:08 DEBUG : file1: reading active writers 2026/04/18 05:28:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:09 DEBUG : Looking for writers 2026/04/18 05:28:09 DEBUG : file1: reading active writers 2026/04/18 05:28:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:10 DEBUG : Looking for writers 2026/04/18 05:28:10 DEBUG : file1: reading active writers 2026/04/18 05:28:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:11 DEBUG : Looking for writers 2026/04/18 05:28:11 DEBUG : file1: reading active writers 2026/04/18 05:28:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:12 DEBUG : Looking for writers 2026/04/18 05:28:12 DEBUG : file1: reading active writers 2026/04/18 05:28:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:13 DEBUG : Looking for writers 2026/04/18 05:28:13 DEBUG : file1: reading active writers 2026/04/18 05:28:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:14 DEBUG : Looking for writers 2026/04/18 05:28:14 DEBUG : file1: reading active writers 2026/04/18 05:28:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:15 DEBUG : Looking for writers 2026/04/18 05:28:15 DEBUG : file1: reading active writers 2026/04/18 05:28:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:16 DEBUG : Looking for writers 2026/04/18 05:28:16 DEBUG : file1: reading active writers 2026/04/18 05:28:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:17 DEBUG : Looking for writers 2026/04/18 05:28:17 DEBUG : file1: reading active writers 2026/04/18 05:28:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:17 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:28:18 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:18 ERROR : file1: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:18 DEBUG : Looking for writers 2026/04/18 05:28:18 DEBUG : file1: reading active writers 2026/04/18 05:28:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:19 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x3253987e700 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x325392626c8 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458407474944} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14010948533858344564 ext:211117916652 loc:0x46a1880} ATime:{wall:14010948533858359261 ext:211117931349 loc:0x46a1880} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/18 05:28:19 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:387 Error: Should be true Test: TestRWFileHandleWriteAt Messages: listing wrong, want file1 (11) got fstest.go:203: Not found "file1" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:387 Error: Not equal: expected: 0 actual : 1 Test: TestRWFileHandleWriteAt Messages: 1 objects not found 2026/04/18 05:28:27 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:28:27 DEBUG : Looking for writers 2026/04/18 05:28:27 DEBUG : file1: reading active writers 2026/04/18 05:28:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/18 05:28:27 DEBUG : Looking for writers 2026/04/18 05:28:27 DEBUG : file1: reading active writers 2026/04/18 05:28:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/18 05:28:27 DEBUG : Looking for writers 2026/04/18 05:28:27 DEBUG : file1: reading active writers 2026/04/18 05:28:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/18 05:28:27 DEBUG : Looking for writers 2026/04/18 05:28:27 DEBUG : file1: reading active writers 2026/04/18 05:28:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/18 05:28:27 DEBUG : Looking for writers 2026/04/18 05:28:27 DEBUG : file1: reading active writers 2026/04/18 05:28:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/18 05:28:28 DEBUG : Looking for writers 2026/04/18 05:28:28 DEBUG : file1: reading active writers 2026/04/18 05:28:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/18 05:28:28 DEBUG : Looking for writers 2026/04/18 05:28:28 DEBUG : file1: reading active writers 2026/04/18 05:28:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/18 05:28:29 DEBUG : Looking for writers 2026/04/18 05:28:29 DEBUG : file1: reading active writers 2026/04/18 05:28:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:30 DEBUG : Looking for writers 2026/04/18 05:28:30 DEBUG : file1: reading active writers 2026/04/18 05:28:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:31 DEBUG : Looking for writers 2026/04/18 05:28:31 DEBUG : file1: reading active writers 2026/04/18 05:28:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:32 DEBUG : Looking for writers 2026/04/18 05:28:32 DEBUG : file1: reading active writers 2026/04/18 05:28:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:33 DEBUG : Looking for writers 2026/04/18 05:28:33 DEBUG : file1: reading active writers 2026/04/18 05:28:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:34 DEBUG : Looking for writers 2026/04/18 05:28:34 DEBUG : file1: reading active writers 2026/04/18 05:28:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:35 DEBUG : Looking for writers 2026/04/18 05:28:35 DEBUG : file1: reading active writers 2026/04/18 05:28:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:36 DEBUG : Looking for writers 2026/04/18 05:28:36 DEBUG : file1: reading active writers 2026/04/18 05:28:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:37 DEBUG : Looking for writers 2026/04/18 05:28:37 DEBUG : file1: reading active writers 2026/04/18 05:28:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:38 DEBUG : Looking for writers 2026/04/18 05:28:38 DEBUG : file1: reading active writers 2026/04/18 05:28:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:39 DEBUG : Looking for writers 2026/04/18 05:28:39 DEBUG : file1: reading active writers 2026/04/18 05:28:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:40 DEBUG : Looking for writers 2026/04/18 05:28:40 DEBUG : file1: reading active writers 2026/04/18 05:28:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:41 DEBUG : Looking for writers 2026/04/18 05:28:41 DEBUG : file1: reading active writers 2026/04/18 05:28:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:42 DEBUG : Looking for writers 2026/04/18 05:28:42 DEBUG : file1: reading active writers 2026/04/18 05:28:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:43 DEBUG : Looking for writers 2026/04/18 05:28:43 DEBUG : file1: reading active writers 2026/04/18 05:28:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:43 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:28:44 DEBUG : Looking for writers 2026/04/18 05:28:44 DEBUG : file1: reading active writers 2026/04/18 05:28:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:44 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:44 ERROR : file1: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:45 DEBUG : Looking for writers 2026/04/18 05:28:45 DEBUG : file1: reading active writers 2026/04/18 05:28:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:46 DEBUG : Looking for writers 2026/04/18 05:28:46 DEBUG : file1: reading active writers 2026/04/18 05:28:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:47 DEBUG : Looking for writers 2026/04/18 05:28:47 DEBUG : file1: reading active writers 2026/04/18 05:28:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:48 DEBUG : Looking for writers 2026/04/18 05:28:48 DEBUG : file1: reading active writers 2026/04/18 05:28:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:49 DEBUG : Looking for writers 2026/04/18 05:28:49 DEBUG : file1: reading active writers 2026/04/18 05:28:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:49 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2026/04/18 05:28:49 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11) 2026/04/18 05:28:50 DEBUG : Looking for writers 2026/04/18 05:28:50 DEBUG : file1: reading active writers 2026/04/18 05:28:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:51 DEBUG : Looking for writers 2026/04/18 05:28:51 DEBUG : file1: reading active writers 2026/04/18 05:28:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:52 DEBUG : Looking for writers 2026/04/18 05:28:52 DEBUG : file1: reading active writers 2026/04/18 05:28:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:53 DEBUG : Looking for writers 2026/04/18 05:28:53 DEBUG : file1: reading active writers 2026/04/18 05:28:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:54 DEBUG : Looking for writers 2026/04/18 05:28:54 DEBUG : file1: reading active writers 2026/04/18 05:28:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:55 DEBUG : Looking for writers 2026/04/18 05:28:55 DEBUG : file1: reading active writers 2026/04/18 05:28:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:56 DEBUG : Looking for writers 2026/04/18 05:28:56 DEBUG : file1: reading active writers 2026/04/18 05:28:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:57 DEBUG : Looking for writers 2026/04/18 05:28:57 DEBUG : file1: reading active writers 2026/04/18 05:28:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:28:57 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x3253987e700 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x325392626c8 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458407474944} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14010948533858344564 ext:211117916652 loc:0x46a1880} ATime:{wall:14010948533858359261 ext:211117931349 loc:0x46a1880} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/18 05:28:57 DEBUG : >WaitForWriters: 2026/04/18 05:28:57 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteAt (68.96s) === RUN TestRWFileHandleWriteNoWrite run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:28:58 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:28:58 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:28:58 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:28:58 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:28:58 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:28:58 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:28:58 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:28:58 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:28:58 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:28:58 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:28:58 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:28:58 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/18 05:28:58 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:28:58 DEBUG : file1: newRWFileHandle: 2026/04/18 05:28:58 DEBUG : file1(0x32539378840): openPending: 2026/04/18 05:28:58 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/18 05:28:58 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:28:58 DEBUG : file1(0x32539378840): >openPending: err= 2026/04/18 05:28:58 DEBUG : file1: >newRWFileHandle: err= 2026/04/18 05:28:58 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:28:58 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/18 05:28:58 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/04/18 05:28:58 DEBUG : file1(0x32539378840): close: 2026/04/18 05:28:58 DEBUG : file1: vfs cache: setting modification time to 2026-04-18 05:28:58.280851551 +0000 UTC m=+280.014457303 2026/04/18 05:28:58 INFO : file1: vfs cache: queuing for upload in 100ms 2026/04/18 05:28:58 DEBUG : file1(0x32539378840): >close: err= 2026/04/18 05:28:58 DEBUG : file2: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2026/04/18 05:28:58 DEBUG : file2: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2026/04/18 05:28:58 DEBUG : file2: newRWFileHandle: 2026/04/18 05:28:58 DEBUG : file2(0x32539378d40): openPending: 2026/04/18 05:28:58 DEBUG : file2: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/18 05:28:58 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/04/18 05:28:58 DEBUG : file2(0x32539378d40): >openPending: err= 2026/04/18 05:28:58 DEBUG : file2: >newRWFileHandle: err= 2026/04/18 05:28:58 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/04/18 05:28:58 DEBUG : file2: >Open: fd=file2 (rw), err= 2026/04/18 05:28:58 DEBUG : file2: >OpenFile: fd=file2 (rw), err= 2026/04/18 05:28:58 DEBUG : file2(0x32539378d40): RWFileHandle.Flush 2026/04/18 05:28:58 DEBUG : file2(0x32539378d40): RWFileHandle.Release 2026/04/18 05:28:58 DEBUG : file2(0x32539378d40): close: 2026/04/18 05:28:58 DEBUG : file2: vfs cache: setting modification time to 2026-04-18 05:28:58.281622196 +0000 UTC m=+280.015227948 2026/04/18 05:28:58 INFO : file2: vfs cache: queuing for upload in 100ms 2026/04/18 05:28:58 DEBUG : file2(0x32539378d40): >close: err= 2026/04/18 05:28:58 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:28:58 DEBUG : Looking for writers 2026/04/18 05:28:58 DEBUG : file1: reading active writers 2026/04/18 05:28:58 DEBUG : file2: reading active writers 2026/04/18 05:28:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 10ms 2026/04/18 05:28:58 DEBUG : Looking for writers 2026/04/18 05:28:58 DEBUG : file1: reading active writers 2026/04/18 05:28:58 DEBUG : file2: reading active writers 2026/04/18 05:28:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 20ms 2026/04/18 05:28:58 DEBUG : Looking for writers 2026/04/18 05:28:58 DEBUG : file1: reading active writers 2026/04/18 05:28:58 DEBUG : file2: reading active writers 2026/04/18 05:28:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 40ms 2026/04/18 05:28:58 DEBUG : Looking for writers 2026/04/18 05:28:58 DEBUG : file1: reading active writers 2026/04/18 05:28:58 DEBUG : file2: reading active writers 2026/04/18 05:28:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 80ms 2026/04/18 05:28:58 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:28:58 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:28:58 DEBUG : Looking for writers 2026/04/18 05:28:58 DEBUG : file1: reading active writers 2026/04/18 05:28:58 DEBUG : file2: reading active writers 2026/04/18 05:28:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 160ms 2026/04/18 05:28:58 DEBUG : Looking for writers 2026/04/18 05:28:58 DEBUG : file1: reading active writers 2026/04/18 05:28:58 DEBUG : file2: reading active writers 2026/04/18 05:28:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 320ms 2026/04/18 05:28:58 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:58 ERROR : file2: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:58 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:58 ERROR : file1: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:58 DEBUG : Looking for writers 2026/04/18 05:28:58 DEBUG : file1: reading active writers 2026/04/18 05:28:58 DEBUG : file2: reading active writers 2026/04/18 05:28:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 640ms 2026/04/18 05:28:58 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:28:59 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:28:59 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:59 ERROR : file2: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:59 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:59 ERROR : file1: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:28:59 DEBUG : Looking for writers 2026/04/18 05:28:59 DEBUG : file1: reading active writers 2026/04/18 05:28:59 DEBUG : file2: reading active writers 2026/04/18 05:28:59 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:28:59 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:28:59 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:29:00 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:00 ERROR : file2: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:00 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:00 ERROR : file1: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:00 DEBUG : Looking for writers 2026/04/18 05:29:00 DEBUG : file2: reading active writers 2026/04/18 05:29:00 DEBUG : file1: reading active writers 2026/04/18 05:29:00 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:01 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:29:01 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:29:01 DEBUG : Looking for writers 2026/04/18 05:29:01 DEBUG : file1: reading active writers 2026/04/18 05:29:01 DEBUG : file2: reading active writers 2026/04/18 05:29:01 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:01 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:01 ERROR : file2: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:01 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:01 ERROR : file1: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:02 DEBUG : Looking for writers 2026/04/18 05:29:02 DEBUG : file1: reading active writers 2026/04/18 05:29:02 DEBUG : file2: reading active writers 2026/04/18 05:29:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:03 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:29:03 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:29:03 DEBUG : Looking for writers 2026/04/18 05:29:03 DEBUG : file2: reading active writers 2026/04/18 05:29:03 DEBUG : file1: reading active writers 2026/04/18 05:29:03 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:03 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:03 ERROR : file2: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:03 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:03 ERROR : file1: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:04 DEBUG : Looking for writers 2026/04/18 05:29:04 DEBUG : file2: reading active writers 2026/04/18 05:29:04 DEBUG : file1: reading active writers 2026/04/18 05:29:04 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:05 DEBUG : Looking for writers 2026/04/18 05:29:05 DEBUG : file1: reading active writers 2026/04/18 05:29:05 DEBUG : file2: reading active writers 2026/04/18 05:29:05 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:06 DEBUG : Looking for writers 2026/04/18 05:29:06 DEBUG : file1: reading active writers 2026/04/18 05:29:06 DEBUG : file2: reading active writers 2026/04/18 05:29:06 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:06 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:29:06 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:29:07 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:07 ERROR : file2: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:07 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:07 ERROR : file1: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:07 DEBUG : Looking for writers 2026/04/18 05:29:07 DEBUG : file1: reading active writers 2026/04/18 05:29:07 DEBUG : file2: reading active writers 2026/04/18 05:29:07 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:08 DEBUG : Looking for writers 2026/04/18 05:29:08 DEBUG : file2: reading active writers 2026/04/18 05:29:08 DEBUG : file1: reading active writers 2026/04/18 05:29:08 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:09 DEBUG : Looking for writers 2026/04/18 05:29:09 DEBUG : file1: reading active writers 2026/04/18 05:29:09 DEBUG : file2: reading active writers 2026/04/18 05:29:09 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:10 DEBUG : Looking for writers 2026/04/18 05:29:10 DEBUG : file1: reading active writers 2026/04/18 05:29:10 DEBUG : file2: reading active writers 2026/04/18 05:29:10 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:11 DEBUG : Looking for writers 2026/04/18 05:29:11 DEBUG : file1: reading active writers 2026/04/18 05:29:11 DEBUG : file2: reading active writers 2026/04/18 05:29:11 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:12 DEBUG : Looking for writers 2026/04/18 05:29:12 DEBUG : file1: reading active writers 2026/04/18 05:29:12 DEBUG : file2: reading active writers 2026/04/18 05:29:12 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:13 DEBUG : Looking for writers 2026/04/18 05:29:13 DEBUG : file1: reading active writers 2026/04/18 05:29:13 DEBUG : file2: reading active writers 2026/04/18 05:29:13 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:13 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:29:13 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:29:14 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:14 ERROR : file2: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:14 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:14 ERROR : file1: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:14 DEBUG : Looking for writers 2026/04/18 05:29:14 DEBUG : file1: reading active writers 2026/04/18 05:29:14 DEBUG : file2: reading active writers 2026/04/18 05:29:14 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:15 DEBUG : Looking for writers 2026/04/18 05:29:15 DEBUG : file1: reading active writers 2026/04/18 05:29:15 DEBUG : file2: reading active writers 2026/04/18 05:29:15 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:16 DEBUG : Looking for writers 2026/04/18 05:29:16 DEBUG : file1: reading active writers 2026/04/18 05:29:16 DEBUG : file2: reading active writers 2026/04/18 05:29:16 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:17 DEBUG : Looking for writers 2026/04/18 05:29:17 DEBUG : file2: reading active writers 2026/04/18 05:29:17 DEBUG : file1: reading active writers 2026/04/18 05:29:17 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:18 DEBUG : Looking for writers 2026/04/18 05:29:18 DEBUG : file1: reading active writers 2026/04/18 05:29:18 DEBUG : file2: reading active writers 2026/04/18 05:29:18 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:19 DEBUG : Looking for writers 2026/04/18 05:29:19 DEBUG : file1: reading active writers 2026/04/18 05:29:19 DEBUG : file2: reading active writers 2026/04/18 05:29:19 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:20 DEBUG : Looking for writers 2026/04/18 05:29:20 DEBUG : file1: reading active writers 2026/04/18 05:29:20 DEBUG : file2: reading active writers 2026/04/18 05:29:20 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:21 DEBUG : Looking for writers 2026/04/18 05:29:21 DEBUG : file1: reading active writers 2026/04/18 05:29:21 DEBUG : file2: reading active writers 2026/04/18 05:29:21 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:22 DEBUG : Looking for writers 2026/04/18 05:29:22 DEBUG : file1: reading active writers 2026/04/18 05:29:22 DEBUG : file2: reading active writers 2026/04/18 05:29:22 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:23 DEBUG : Looking for writers 2026/04/18 05:29:23 DEBUG : file2: reading active writers 2026/04/18 05:29:23 DEBUG : file1: reading active writers 2026/04/18 05:29:23 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:24 DEBUG : Looking for writers 2026/04/18 05:29:24 DEBUG : file2: reading active writers 2026/04/18 05:29:24 DEBUG : file1: reading active writers 2026/04/18 05:29:24 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:25 DEBUG : Looking for writers 2026/04/18 05:29:25 DEBUG : file1: reading active writers 2026/04/18 05:29:25 DEBUG : file2: reading active writers 2026/04/18 05:29:25 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:26 DEBUG : Looking for writers 2026/04/18 05:29:26 DEBUG : file1: reading active writers 2026/04/18 05:29:26 DEBUG : file2: reading active writers 2026/04/18 05:29:26 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:26 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:29:26 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:29:27 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:27 ERROR : file2: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:27 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:27 ERROR : file1: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:27 DEBUG : Looking for writers 2026/04/18 05:29:27 DEBUG : file1: reading active writers 2026/04/18 05:29:27 DEBUG : file2: reading active writers 2026/04/18 05:29:27 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:28 ERROR : Exiting even though 0 writers active and 2 cache items in use after 30s Cache{ "file1": &{c:0x3253935a700 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x325392eec68 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458408049824} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14010948607843071071 ext:280014457303 loc:0x46a1880} ATime:{wall:14010948607843231443 ext:280014617675 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, "file2": &{c:0x3253935a700 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x325392eeea8 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458408050400} name:file2 opens:0 downloaders: o: fd: info:{ModTime:{wall:14010948607843841716 ext:280015227948 loc:0x46a1880} ATime:{wall:14010948607843973225 ext:280015359457 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:2 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/18 05:29:28 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:420 Error: Should be true Test: TestRWFileHandleWriteNoWrite Messages: listing wrong, want file1 (0), file2 (0) got fstest.go:203: Not found "file1" fstest.go:203: Not found "file2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:420 Error: Not equal: expected: 0 actual : 2 Test: TestRWFileHandleWriteNoWrite Messages: 2 objects not found 2026/04/18 05:29:36 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:29:36 DEBUG : Looking for writers 2026/04/18 05:29:36 DEBUG : file1: reading active writers 2026/04/18 05:29:36 DEBUG : file2: reading active writers 2026/04/18 05:29:36 DEBUG : Still 0 writers active and 2 cache items in use, waiting 10ms 2026/04/18 05:29:36 DEBUG : Looking for writers 2026/04/18 05:29:36 DEBUG : file1: reading active writers 2026/04/18 05:29:36 DEBUG : file2: reading active writers 2026/04/18 05:29:36 DEBUG : Still 0 writers active and 2 cache items in use, waiting 20ms 2026/04/18 05:29:36 DEBUG : Looking for writers 2026/04/18 05:29:36 DEBUG : file1: reading active writers 2026/04/18 05:29:36 DEBUG : file2: reading active writers 2026/04/18 05:29:36 DEBUG : Still 0 writers active and 2 cache items in use, waiting 40ms 2026/04/18 05:29:36 DEBUG : Looking for writers 2026/04/18 05:29:36 DEBUG : file1: reading active writers 2026/04/18 05:29:36 DEBUG : file2: reading active writers 2026/04/18 05:29:36 DEBUG : Still 0 writers active and 2 cache items in use, waiting 80ms 2026/04/18 05:29:36 DEBUG : Looking for writers 2026/04/18 05:29:36 DEBUG : file1: reading active writers 2026/04/18 05:29:36 DEBUG : file2: reading active writers 2026/04/18 05:29:36 DEBUG : Still 0 writers active and 2 cache items in use, waiting 160ms 2026/04/18 05:29:36 DEBUG : Looking for writers 2026/04/18 05:29:36 DEBUG : file1: reading active writers 2026/04/18 05:29:36 DEBUG : file2: reading active writers 2026/04/18 05:29:36 DEBUG : Still 0 writers active and 2 cache items in use, waiting 320ms 2026/04/18 05:29:37 DEBUG : Looking for writers 2026/04/18 05:29:37 DEBUG : file1: reading active writers 2026/04/18 05:29:37 DEBUG : file2: reading active writers 2026/04/18 05:29:37 DEBUG : Still 0 writers active and 2 cache items in use, waiting 640ms 2026/04/18 05:29:37 DEBUG : Looking for writers 2026/04/18 05:29:37 DEBUG : file1: reading active writers 2026/04/18 05:29:37 DEBUG : file2: reading active writers 2026/04/18 05:29:37 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:38 DEBUG : Looking for writers 2026/04/18 05:29:38 DEBUG : file1: reading active writers 2026/04/18 05:29:38 DEBUG : file2: reading active writers 2026/04/18 05:29:38 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:39 DEBUG : Looking for writers 2026/04/18 05:29:39 DEBUG : file1: reading active writers 2026/04/18 05:29:39 DEBUG : file2: reading active writers 2026/04/18 05:29:39 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:40 DEBUG : Looking for writers 2026/04/18 05:29:40 DEBUG : file1: reading active writers 2026/04/18 05:29:40 DEBUG : file2: reading active writers 2026/04/18 05:29:40 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:41 DEBUG : Looking for writers 2026/04/18 05:29:41 DEBUG : file1: reading active writers 2026/04/18 05:29:41 DEBUG : file2: reading active writers 2026/04/18 05:29:41 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:42 DEBUG : Looking for writers 2026/04/18 05:29:42 DEBUG : file1: reading active writers 2026/04/18 05:29:42 DEBUG : file2: reading active writers 2026/04/18 05:29:42 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:43 DEBUG : Looking for writers 2026/04/18 05:29:43 DEBUG : file1: reading active writers 2026/04/18 05:29:43 DEBUG : file2: reading active writers 2026/04/18 05:29:43 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:44 DEBUG : Looking for writers 2026/04/18 05:29:44 DEBUG : file1: reading active writers 2026/04/18 05:29:44 DEBUG : file2: reading active writers 2026/04/18 05:29:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:45 DEBUG : Looking for writers 2026/04/18 05:29:45 DEBUG : file1: reading active writers 2026/04/18 05:29:45 DEBUG : file2: reading active writers 2026/04/18 05:29:45 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:46 DEBUG : Looking for writers 2026/04/18 05:29:46 DEBUG : file1: reading active writers 2026/04/18 05:29:46 DEBUG : file2: reading active writers 2026/04/18 05:29:46 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:47 DEBUG : Looking for writers 2026/04/18 05:29:47 DEBUG : file1: reading active writers 2026/04/18 05:29:47 DEBUG : file2: reading active writers 2026/04/18 05:29:47 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:48 DEBUG : Looking for writers 2026/04/18 05:29:48 DEBUG : file1: reading active writers 2026/04/18 05:29:48 DEBUG : file2: reading active writers 2026/04/18 05:29:48 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:49 DEBUG : Looking for writers 2026/04/18 05:29:49 DEBUG : file1: reading active writers 2026/04/18 05:29:49 DEBUG : file2: reading active writers 2026/04/18 05:29:49 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:50 DEBUG : Looking for writers 2026/04/18 05:29:50 DEBUG : file1: reading active writers 2026/04/18 05:29:50 DEBUG : file2: reading active writers 2026/04/18 05:29:50 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:51 DEBUG : Looking for writers 2026/04/18 05:29:51 DEBUG : file1: reading active writers 2026/04/18 05:29:51 DEBUG : file2: reading active writers 2026/04/18 05:29:51 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:52 DEBUG : file2: vfs cache: starting upload 2026/04/18 05:29:52 DEBUG : file1: vfs cache: starting upload 2026/04/18 05:29:52 DEBUG : Looking for writers 2026/04/18 05:29:52 DEBUG : file1: reading active writers 2026/04/18 05:29:52 DEBUG : file2: reading active writers 2026/04/18 05:29:52 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:53 ERROR : file2: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:53 ERROR : file2: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:53 ERROR : file1: Failed to copy: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:53 ERROR : file1: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:29:53 DEBUG : Looking for writers 2026/04/18 05:29:53 DEBUG : file1: reading active writers 2026/04/18 05:29:53 DEBUG : file2: reading active writers 2026/04/18 05:29:53 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:54 DEBUG : Looking for writers 2026/04/18 05:29:54 DEBUG : file1: reading active writers 2026/04/18 05:29:54 DEBUG : file2: reading active writers 2026/04/18 05:29:54 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:55 DEBUG : Looking for writers 2026/04/18 05:29:55 DEBUG : file1: reading active writers 2026/04/18 05:29:55 DEBUG : file2: reading active writers 2026/04/18 05:29:55 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:56 DEBUG : Looking for writers 2026/04/18 05:29:56 DEBUG : file1: reading active writers 2026/04/18 05:29:56 DEBUG : file2: reading active writers 2026/04/18 05:29:56 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:57 DEBUG : Looking for writers 2026/04/18 05:29:57 DEBUG : file1: reading active writers 2026/04/18 05:29:57 DEBUG : file2: reading active writers 2026/04/18 05:29:57 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:58 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2026/04/18 05:29:58 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file2 not removed, freed 0 bytes 2026/04/18 05:29:58 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 2 (was 2) in use 2, to upload 2, uploading 0, total size 0 (was 0) 2026/04/18 05:29:58 DEBUG : Looking for writers 2026/04/18 05:29:58 DEBUG : file1: reading active writers 2026/04/18 05:29:58 DEBUG : file2: reading active writers 2026/04/18 05:29:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:29:59 DEBUG : Looking for writers 2026/04/18 05:29:59 DEBUG : file1: reading active writers 2026/04/18 05:29:59 DEBUG : file2: reading active writers 2026/04/18 05:29:59 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:30:00 DEBUG : Looking for writers 2026/04/18 05:30:00 DEBUG : file1: reading active writers 2026/04/18 05:30:00 DEBUG : file2: reading active writers 2026/04/18 05:30:00 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:30:01 DEBUG : Looking for writers 2026/04/18 05:30:01 DEBUG : file1: reading active writers 2026/04/18 05:30:01 DEBUG : file2: reading active writers 2026/04/18 05:30:01 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:30:02 DEBUG : Looking for writers 2026/04/18 05:30:02 DEBUG : file1: reading active writers 2026/04/18 05:30:02 DEBUG : file2: reading active writers 2026/04/18 05:30:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:30:03 DEBUG : Looking for writers 2026/04/18 05:30:03 DEBUG : file1: reading active writers 2026/04/18 05:30:03 DEBUG : file2: reading active writers 2026/04/18 05:30:03 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:30:04 DEBUG : Looking for writers 2026/04/18 05:30:04 DEBUG : file1: reading active writers 2026/04/18 05:30:04 DEBUG : file2: reading active writers 2026/04/18 05:30:04 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:30:05 DEBUG : Looking for writers 2026/04/18 05:30:05 DEBUG : file2: reading active writers 2026/04/18 05:30:05 DEBUG : file1: reading active writers 2026/04/18 05:30:05 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/18 05:30:06 ERROR : Exiting even though 0 writers active and 2 cache items in use after 30s Cache{ "file1": &{c:0x3253935a700 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x325392eec68 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458408049824} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14010948607843071071 ext:280014457303 loc:0x46a1880} ATime:{wall:14010948607843231443 ext:280014617675 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, "file2": &{c:0x3253935a700 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x325392eeea8 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458408050400} name:file2 opens:0 downloaders: o: fd: info:{ModTime:{wall:14010948607843841716 ext:280015227948 loc:0x46a1880} ATime:{wall:14010948607843973225 ext:280015359457 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:2 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/18 05:30:06 DEBUG : >WaitForWriters: 2026/04/18 05:30:06 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteNoWrite (68.99s) === RUN TestRWFileHandleSizeTruncateExisting run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:30:07 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:30:07 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:30:07 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:07 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:07 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:07 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:30:07 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:07 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:07 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:30:07 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:07 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:08 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:30:08 DEBUG : Looking for writers 2026/04/18 05:30:08 DEBUG : >WaitForWriters: 2026/04/18 05:30:08 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeTruncateExisting (2.52s) === RUN TestRWFileHandleSizeCreateExisting run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:30:09 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:30:09 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:30:09 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:09 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:09 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:09 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:30:09 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:09 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:09 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:30:09 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:09 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:10 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:30:10 DEBUG : Looking for writers 2026/04/18 05:30:10 DEBUG : >WaitForWriters: 2026/04/18 05:30:10 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeCreateExisting (2.40s) === RUN TestRWFileModTimeWithOpenWriters run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:30:12 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:30:12 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 05:30:12 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:12 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:12 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:12 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:30:12 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:12 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:12 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 05:30:12 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-nelezit5dace" 2026/04/18 05:30:12 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:30:12 INFO : drime root 'rclone-test-nelezit5dace': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/18 05:30:12 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:30:12 DEBUG : file1: newRWFileHandle: 2026/04/18 05:30:12 DEBUG : file1(0x32538b6c140): openPending: 2026/04/18 05:30:12 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/18 05:30:12 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:30:12 DEBUG : file1(0x32538b6c140): >openPending: err= 2026/04/18 05:30:12 DEBUG : file1: >newRWFileHandle: err= 2026/04/18 05:30:12 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:30:12 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/18 05:30:12 DEBUG : file1: >OpenFile: fd=file1 (rw), err= run.go:290: Failed to put "time_test" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:12 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:30:12 DEBUG : Looking for writers 2026/04/18 05:30:12 DEBUG : file1: reading active writers 2026/04/18 05:30:12 DEBUG : file1: active writers 1 2026/04/18 05:30:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 10ms 2026/04/18 05:30:12 DEBUG : Looking for writers 2026/04/18 05:30:12 DEBUG : file1: reading active writers 2026/04/18 05:30:12 DEBUG : file1: active writers 1 2026/04/18 05:30:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 20ms 2026/04/18 05:30:12 DEBUG : Looking for writers 2026/04/18 05:30:12 DEBUG : file1: reading active writers 2026/04/18 05:30:12 DEBUG : file1: active writers 1 2026/04/18 05:30:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 40ms 2026/04/18 05:30:12 DEBUG : Looking for writers 2026/04/18 05:30:12 DEBUG : file1: reading active writers 2026/04/18 05:30:12 DEBUG : file1: active writers 1 2026/04/18 05:30:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 80ms 2026/04/18 05:30:12 DEBUG : Looking for writers 2026/04/18 05:30:12 DEBUG : file1: reading active writers 2026/04/18 05:30:12 DEBUG : file1: active writers 1 2026/04/18 05:30:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 160ms 2026/04/18 05:30:12 DEBUG : Looking for writers 2026/04/18 05:30:12 DEBUG : file1: reading active writers 2026/04/18 05:30:12 DEBUG : file1: active writers 1 2026/04/18 05:30:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 320ms 2026/04/18 05:30:13 DEBUG : Looking for writers 2026/04/18 05:30:13 DEBUG : file1: reading active writers 2026/04/18 05:30:13 DEBUG : file1: active writers 1 2026/04/18 05:30:13 DEBUG : Still 1 writers active and 1 cache items in use, waiting 640ms 2026/04/18 05:30:13 DEBUG : Looking for writers 2026/04/18 05:30:13 DEBUG : file1: reading active writers 2026/04/18 05:30:13 DEBUG : file1: active writers 1 2026/04/18 05:30:13 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:14 DEBUG : Looking for writers 2026/04/18 05:30:14 DEBUG : file1: reading active writers 2026/04/18 05:30:14 DEBUG : file1: active writers 1 2026/04/18 05:30:14 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:15 DEBUG : Looking for writers 2026/04/18 05:30:15 DEBUG : file1: reading active writers 2026/04/18 05:30:15 DEBUG : file1: active writers 1 2026/04/18 05:30:15 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:16 DEBUG : Looking for writers 2026/04/18 05:30:16 DEBUG : file1: reading active writers 2026/04/18 05:30:16 DEBUG : file1: active writers 1 2026/04/18 05:30:16 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:17 DEBUG : Looking for writers 2026/04/18 05:30:17 DEBUG : file1: reading active writers 2026/04/18 05:30:17 DEBUG : file1: active writers 1 2026/04/18 05:30:17 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:18 DEBUG : Looking for writers 2026/04/18 05:30:18 DEBUG : file1: reading active writers 2026/04/18 05:30:18 DEBUG : file1: active writers 1 2026/04/18 05:30:18 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:19 DEBUG : Looking for writers 2026/04/18 05:30:19 DEBUG : file1: reading active writers 2026/04/18 05:30:19 DEBUG : file1: active writers 1 2026/04/18 05:30:19 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:20 DEBUG : Looking for writers 2026/04/18 05:30:20 DEBUG : file1: reading active writers 2026/04/18 05:30:20 DEBUG : file1: active writers 1 2026/04/18 05:30:20 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:21 DEBUG : Looking for writers 2026/04/18 05:30:21 DEBUG : file1: reading active writers 2026/04/18 05:30:21 DEBUG : file1: active writers 1 2026/04/18 05:30:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:22 DEBUG : Looking for writers 2026/04/18 05:30:22 DEBUG : file1: reading active writers 2026/04/18 05:30:22 DEBUG : file1: active writers 1 2026/04/18 05:30:22 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:23 DEBUG : Looking for writers 2026/04/18 05:30:23 DEBUG : file1: reading active writers 2026/04/18 05:30:23 DEBUG : file1: active writers 1 2026/04/18 05:30:23 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:24 DEBUG : Looking for writers 2026/04/18 05:30:24 DEBUG : file1: reading active writers 2026/04/18 05:30:24 DEBUG : file1: active writers 1 2026/04/18 05:30:24 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:25 DEBUG : Looking for writers 2026/04/18 05:30:25 DEBUG : file1: reading active writers 2026/04/18 05:30:25 DEBUG : file1: active writers 1 2026/04/18 05:30:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:26 DEBUG : Looking for writers 2026/04/18 05:30:26 DEBUG : file1: reading active writers 2026/04/18 05:30:26 DEBUG : file1: active writers 1 2026/04/18 05:30:26 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:27 DEBUG : Looking for writers 2026/04/18 05:30:27 DEBUG : file1: reading active writers 2026/04/18 05:30:27 DEBUG : file1: active writers 1 2026/04/18 05:30:27 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:28 DEBUG : Looking for writers 2026/04/18 05:30:28 DEBUG : file1: reading active writers 2026/04/18 05:30:28 DEBUG : file1: active writers 1 2026/04/18 05:30:28 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:29 DEBUG : Looking for writers 2026/04/18 05:30:29 DEBUG : file1: reading active writers 2026/04/18 05:30:29 DEBUG : file1: active writers 1 2026/04/18 05:30:29 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:30 DEBUG : Looking for writers 2026/04/18 05:30:30 DEBUG : file1: reading active writers 2026/04/18 05:30:30 DEBUG : file1: active writers 1 2026/04/18 05:30:30 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:31 DEBUG : Looking for writers 2026/04/18 05:30:31 DEBUG : file1: reading active writers 2026/04/18 05:30:31 DEBUG : file1: active writers 1 2026/04/18 05:30:31 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:32 DEBUG : Looking for writers 2026/04/18 05:30:32 DEBUG : file1: reading active writers 2026/04/18 05:30:32 DEBUG : file1: active writers 1 2026/04/18 05:30:32 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:33 DEBUG : Looking for writers 2026/04/18 05:30:33 DEBUG : file1: reading active writers 2026/04/18 05:30:33 DEBUG : file1: active writers 1 2026/04/18 05:30:33 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:34 DEBUG : Looking for writers 2026/04/18 05:30:34 DEBUG : file1: reading active writers 2026/04/18 05:30:34 DEBUG : file1: active writers 1 2026/04/18 05:30:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:35 DEBUG : Looking for writers 2026/04/18 05:30:35 DEBUG : file1: reading active writers 2026/04/18 05:30:35 DEBUG : file1: active writers 1 2026/04/18 05:30:35 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:36 DEBUG : Looking for writers 2026/04/18 05:30:36 DEBUG : file1: reading active writers 2026/04/18 05:30:36 DEBUG : file1: active writers 1 2026/04/18 05:30:36 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:37 DEBUG : Looking for writers 2026/04/18 05:30:37 DEBUG : file1: reading active writers 2026/04/18 05:30:37 DEBUG : file1: active writers 1 2026/04/18 05:30:37 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:38 DEBUG : Looking for writers 2026/04/18 05:30:38 DEBUG : file1: reading active writers 2026/04/18 05:30:38 DEBUG : file1: active writers 1 2026/04/18 05:30:38 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:39 DEBUG : Looking for writers 2026/04/18 05:30:39 DEBUG : file1: reading active writers 2026/04/18 05:30:39 DEBUG : file1: active writers 1 2026/04/18 05:30:39 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:40 DEBUG : Looking for writers 2026/04/18 05:30:40 DEBUG : file1: reading active writers 2026/04/18 05:30:40 DEBUG : file1: active writers 1 2026/04/18 05:30:40 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:41 DEBUG : Looking for writers 2026/04/18 05:30:41 DEBUG : file1: reading active writers 2026/04/18 05:30:41 DEBUG : file1: active writers 1 2026/04/18 05:30:41 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/18 05:30:42 ERROR : Exiting even though 1 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x3253987ef00 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x325392e6368 notify:{wait:0 notify:0 lock:0 head: tail:} checker:3458408014752} name:file1 opens:1 downloaders: o: fd:0x32538b82298 info:{ModTime:{wall:14010948687219464790 ext:353933956036 loc:0x46a1880} ATime:{wall:14010948687219464790 ext:353933956036 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:0 pendingAccesses:0 modified:true beingReset:false graceTimer:}, } 2026/04/18 05:30:42 DEBUG : >WaitForWriters: 2026/04/18 05:30:42 DEBUG : drime root 'rclone-test-nelezit5dace': vfs cache: cleaner exiting --- FAIL: TestRWFileModTimeWithOpenWriters (30.93s) === RUN TestCaseSensitivity run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" run.go:290: Failed to put "FiLeA" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." --- FAIL: TestCaseSensitivity (0.78s) === RUN TestUnicodeNormalization run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" run.go:290: Failed to put "normal name with no special characters.txt" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." --- FAIL: TestUnicodeNormalization (0.73s) === RUN TestVFSStat run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:30:44 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:44 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:30:44 DEBUG : Looking for writers 2026/04/18 05:30:44 DEBUG : >WaitForWriters: --- FAIL: TestVFSStat (0.74s) === RUN TestVFSStatParent run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:30:45 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:45 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:30:45 DEBUG : Looking for writers 2026/04/18 05:30:45 DEBUG : >WaitForWriters: --- FAIL: TestVFSStatParent (0.74s) === RUN TestVFSOpenFile run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:30:45 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "file1" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:46 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:30:46 DEBUG : Looking for writers 2026/04/18 05:30:46 DEBUG : >WaitForWriters: --- FAIL: TestVFSOpenFile (0.78s) === RUN TestVFSRename run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:30:46 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file2" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:47 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:30:47 DEBUG : Looking for writers 2026/04/18 05:30:47 DEBUG : >WaitForWriters: --- FAIL: TestVFSRename (2.69s) === RUN TestWriteFileHandleMethods run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:30:49 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:30:49 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:30:49 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:30:49 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:30:49 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:30:49 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:30:49 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:30:49 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2026/04/18 05:30:49 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2026/04/18 05:30:49 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2026/04/18 05:30:49 DEBUG : drime root 'rclone-test-nelezit5dace': File to upload is small (5 bytes), uploading instead of streaming 2026/04/18 05:30:50 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:50 DEBUG : file1: Remove: 2026/04/18 05:30:50 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/18 05:30:50 DEBUG : file1: >Remove: err= write_test.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:144 Error: Received unexpected error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." Test: TestWriteFileHandleMethods dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:153 Error: Not equal: expected: []string{"file1,5,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=13) "file1,5,false" -} +([]string) Test: TestWriteFileHandleMethods fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:157 Error: Should be true Test: TestWriteFileHandleMethods Messages: listing wrong, want file1 (5) got fstest.go:203: Not found "file1" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:157 Error: Not equal: expected: 0 actual : 1 Test: TestWriteFileHandleMethods Messages: 1 objects not found 2026/04/18 05:30:58 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:30:58 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:30:58 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:30:58 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:30:58 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:30:58 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:30:58 DEBUG : drime root 'rclone-test-nelezit5dace': File to upload is small (0 bytes), uploading instead of streaming 2026/04/18 05:30:59 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:30:59 DEBUG : file1: Remove: 2026/04/18 05:30:59 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/18 05:30:59 DEBUG : file1: >Remove: err= write_test.go:163: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:163 Error: Received unexpected error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." Test: TestWriteFileHandleMethods dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:164 Error: Not equal: expected: []string{"file1,5,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=13) "file1,5,false" -} +([]string) Test: TestWriteFileHandleMethods 2026/04/18 05:30:59 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:30:59 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:30:59 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:30:59 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:30:59 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:30:59 DEBUG : Added virtual directory entry vAddFile: "file1" write_test.go:171: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:171 Error: Not equal: expected: *errors.errorString(&errors.errorString{s:"permission denied"}) actual : () Test: TestWriteFileHandleMethods 2026/04/18 05:30:59 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:30:59 DEBUG : Looking for writers 2026/04/18 05:30:59 DEBUG : file1: reading active writers 2026/04/18 05:30:59 DEBUG : file1: active writers 1 2026/04/18 05:30:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms 2026/04/18 05:30:59 DEBUG : Looking for writers 2026/04/18 05:30:59 DEBUG : file1: reading active writers 2026/04/18 05:30:59 DEBUG : file1: active writers 1 2026/04/18 05:30:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms 2026/04/18 05:30:59 DEBUG : Looking for writers 2026/04/18 05:30:59 DEBUG : file1: reading active writers 2026/04/18 05:30:59 DEBUG : file1: active writers 1 2026/04/18 05:30:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms 2026/04/18 05:30:59 DEBUG : Looking for writers 2026/04/18 05:30:59 DEBUG : file1: reading active writers 2026/04/18 05:30:59 DEBUG : file1: active writers 1 2026/04/18 05:30:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms 2026/04/18 05:30:59 DEBUG : Looking for writers 2026/04/18 05:30:59 DEBUG : file1: reading active writers 2026/04/18 05:30:59 DEBUG : file1: active writers 1 2026/04/18 05:30:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms 2026/04/18 05:30:59 DEBUG : Looking for writers 2026/04/18 05:30:59 DEBUG : file1: reading active writers 2026/04/18 05:30:59 DEBUG : file1: active writers 1 2026/04/18 05:30:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms 2026/04/18 05:30:59 DEBUG : Looking for writers 2026/04/18 05:30:59 DEBUG : file1: reading active writers 2026/04/18 05:30:59 DEBUG : file1: active writers 1 2026/04/18 05:30:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms 2026/04/18 05:31:00 DEBUG : Looking for writers 2026/04/18 05:31:00 DEBUG : file1: reading active writers 2026/04/18 05:31:00 DEBUG : file1: active writers 1 2026/04/18 05:31:00 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:01 DEBUG : Looking for writers 2026/04/18 05:31:01 DEBUG : file1: reading active writers 2026/04/18 05:31:01 DEBUG : file1: active writers 1 2026/04/18 05:31:01 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:02 DEBUG : Looking for writers 2026/04/18 05:31:02 DEBUG : file1: reading active writers 2026/04/18 05:31:02 DEBUG : file1: active writers 1 2026/04/18 05:31:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:03 DEBUG : Looking for writers 2026/04/18 05:31:03 DEBUG : file1: reading active writers 2026/04/18 05:31:03 DEBUG : file1: active writers 1 2026/04/18 05:31:03 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:04 DEBUG : Looking for writers 2026/04/18 05:31:04 DEBUG : file1: reading active writers 2026/04/18 05:31:04 DEBUG : file1: active writers 1 2026/04/18 05:31:04 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:05 DEBUG : Looking for writers 2026/04/18 05:31:05 DEBUG : file1: reading active writers 2026/04/18 05:31:05 DEBUG : file1: active writers 1 2026/04/18 05:31:05 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:06 DEBUG : Looking for writers 2026/04/18 05:31:06 DEBUG : file1: reading active writers 2026/04/18 05:31:06 DEBUG : file1: active writers 1 2026/04/18 05:31:06 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:07 DEBUG : Looking for writers 2026/04/18 05:31:07 DEBUG : file1: reading active writers 2026/04/18 05:31:07 DEBUG : file1: active writers 1 2026/04/18 05:31:07 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:08 DEBUG : Looking for writers 2026/04/18 05:31:08 DEBUG : file1: reading active writers 2026/04/18 05:31:08 DEBUG : file1: active writers 1 2026/04/18 05:31:08 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:09 DEBUG : Looking for writers 2026/04/18 05:31:09 DEBUG : file1: reading active writers 2026/04/18 05:31:09 DEBUG : file1: active writers 1 2026/04/18 05:31:09 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:10 DEBUG : Looking for writers 2026/04/18 05:31:10 DEBUG : file1: reading active writers 2026/04/18 05:31:10 DEBUG : file1: active writers 1 2026/04/18 05:31:10 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:11 DEBUG : Looking for writers 2026/04/18 05:31:11 DEBUG : file1: reading active writers 2026/04/18 05:31:11 DEBUG : file1: active writers 1 2026/04/18 05:31:11 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:12 DEBUG : Looking for writers 2026/04/18 05:31:12 DEBUG : file1: reading active writers 2026/04/18 05:31:12 DEBUG : file1: active writers 1 2026/04/18 05:31:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:13 DEBUG : Looking for writers 2026/04/18 05:31:13 DEBUG : file1: reading active writers 2026/04/18 05:31:13 DEBUG : file1: active writers 1 2026/04/18 05:31:13 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:14 DEBUG : Looking for writers 2026/04/18 05:31:14 DEBUG : file1: reading active writers 2026/04/18 05:31:14 DEBUG : file1: active writers 1 2026/04/18 05:31:14 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:15 DEBUG : Looking for writers 2026/04/18 05:31:15 DEBUG : file1: reading active writers 2026/04/18 05:31:15 DEBUG : file1: active writers 1 2026/04/18 05:31:15 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:16 DEBUG : Looking for writers 2026/04/18 05:31:16 DEBUG : file1: reading active writers 2026/04/18 05:31:16 DEBUG : file1: active writers 1 2026/04/18 05:31:16 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:17 DEBUG : Looking for writers 2026/04/18 05:31:17 DEBUG : file1: reading active writers 2026/04/18 05:31:17 DEBUG : file1: active writers 1 2026/04/18 05:31:17 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:18 DEBUG : Looking for writers 2026/04/18 05:31:18 DEBUG : file1: reading active writers 2026/04/18 05:31:18 DEBUG : file1: active writers 1 2026/04/18 05:31:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:19 DEBUG : Looking for writers 2026/04/18 05:31:19 DEBUG : file1: reading active writers 2026/04/18 05:31:19 DEBUG : file1: active writers 1 2026/04/18 05:31:19 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:20 DEBUG : Looking for writers 2026/04/18 05:31:20 DEBUG : file1: reading active writers 2026/04/18 05:31:20 DEBUG : file1: active writers 1 2026/04/18 05:31:20 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:21 DEBUG : Looking for writers 2026/04/18 05:31:21 DEBUG : file1: reading active writers 2026/04/18 05:31:21 DEBUG : file1: active writers 1 2026/04/18 05:31:21 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:22 DEBUG : Looking for writers 2026/04/18 05:31:22 DEBUG : file1: reading active writers 2026/04/18 05:31:22 DEBUG : file1: active writers 1 2026/04/18 05:31:22 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:23 DEBUG : Looking for writers 2026/04/18 05:31:23 DEBUG : file1: reading active writers 2026/04/18 05:31:23 DEBUG : file1: active writers 1 2026/04/18 05:31:23 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:24 DEBUG : Looking for writers 2026/04/18 05:31:24 DEBUG : file1: reading active writers 2026/04/18 05:31:24 DEBUG : file1: active writers 1 2026/04/18 05:31:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:25 DEBUG : Looking for writers 2026/04/18 05:31:25 DEBUG : file1: reading active writers 2026/04/18 05:31:25 DEBUG : file1: active writers 1 2026/04/18 05:31:25 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:26 DEBUG : Looking for writers 2026/04/18 05:31:26 DEBUG : file1: reading active writers 2026/04/18 05:31:26 DEBUG : file1: active writers 1 2026/04/18 05:31:26 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:27 DEBUG : Looking for writers 2026/04/18 05:31:27 DEBUG : file1: reading active writers 2026/04/18 05:31:27 DEBUG : file1: active writers 1 2026/04/18 05:31:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:28 DEBUG : Looking for writers 2026/04/18 05:31:28 DEBUG : file1: reading active writers 2026/04/18 05:31:28 DEBUG : file1: active writers 1 2026/04/18 05:31:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/18 05:31:29 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s Cache: 2026/04/18 05:31:29 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleMethods (40.25s) === RUN TestWriteFileHandleWriteAt run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:29 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:31:29 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:31:29 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:31:29 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:29 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:31:29 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:31:29 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:29 DEBUG : file1: waiting for in-sequence write to 100 for 1s 2026/04/18 05:31:30 DEBUG : file1: aborting in-sequence write wait, off=100 2026/04/18 05:31:30 DEBUG : file1: failed to wait for in-sequence write to 100 2026/04/18 05:31:30 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes 2026/04/18 05:31:30 DEBUG : drime root 'rclone-test-nelezit5dace': File to upload is small (11 bytes), uploading instead of streaming 2026/04/18 05:31:31 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:31 DEBUG : file1: Remove: 2026/04/18 05:31:31 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/18 05:31:31 DEBUG : file1: >Remove: err= write_test.go:221: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:221 Error: Received unexpected error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." Test: TestWriteFileHandleWriteAt 2026/04/18 05:31:31 ERROR : file1: WriteFileHandle.Write: error: Bad file descriptor dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:231 Error: Not equal: expected: []string{"file1,11,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=14) "file1,11,false" -} +([]string) Test: TestWriteFileHandleWriteAt fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:235 Error: Should be true Test: TestWriteFileHandleWriteAt Messages: listing wrong, want file1 (11) got fstest.go:203: Not found "file1" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:235 Error: Not equal: expected: 0 actual : 1 Test: TestWriteFileHandleWriteAt Messages: 1 objects not found 2026/04/18 05:31:39 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:39 DEBUG : Looking for writers 2026/04/18 05:31:39 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleWriteAt (10.68s) === RUN TestWriteFileHandleFlush run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:40 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:31:40 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:31:40 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:31:40 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:40 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:31:40 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:31:40 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2026/04/18 05:31:40 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:40 DEBUG : drime root 'rclone-test-nelezit5dace': File to upload is small (5 bytes), uploading instead of streaming 2026/04/18 05:31:40 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:40 DEBUG : file1: Remove: 2026/04/18 05:31:40 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/18 05:31:40 DEBUG : file1: >Remove: err= 2026/04/18 05:31:40 ERROR : file1: WriteFileHandle.Flush error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." write_test.go:256: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:256 Error: Received unexpected error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." Test: TestWriteFileHandleFlush 2026/04/18 05:31:40 DEBUG : file1: WriteFileHandle.Flush nothing to do dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:267 Error: Not equal: expected: []string{"file1,5,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=13) "file1,5,false" -} +([]string) Test: TestWriteFileHandleFlush 2026/04/18 05:31:40 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:40 DEBUG : Looking for writers 2026/04/18 05:31:40 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleFlush (1.04s) === RUN TestWriteFileHandleRelease run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:41 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:31:41 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:31:41 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:31:41 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:41 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:31:41 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:31:41 DEBUG : file1: WriteFileHandle.Release closing 2026/04/18 05:31:41 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:41 DEBUG : drime root 'rclone-test-nelezit5dace': File to upload is small (0 bytes), uploading instead of streaming 2026/04/18 05:31:41 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:41 DEBUG : file1: Remove: 2026/04/18 05:31:41 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/18 05:31:41 DEBUG : file1: >Remove: err= 2026/04/18 05:31:41 ERROR : file1: WriteFileHandle.Release error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." write_test.go:279: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:279 Error: Received unexpected error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." Test: TestWriteFileHandleRelease 2026/04/18 05:31:41 DEBUG : file1: WriteFileHandle.Release nothing to do 2026/04/18 05:31:41 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:41 DEBUG : Looking for writers 2026/04/18 05:31:41 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleRelease (0.92s) === RUN TestWriteFileModTimeWithOpenWriters run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:42 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:31:42 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:31:42 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:31:42 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:42 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:31:42 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:31:42 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:42 DEBUG : drime root 'rclone-test-nelezit5dace': File to upload is small (2 bytes), uploading instead of streaming 2026/04/18 05:31:42 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:42 DEBUG : file1: Remove: 2026/04/18 05:31:42 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/18 05:31:42 DEBUG : file1: >Remove: err= write_test.go:333: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:333 Error: Received unexpected error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." Test: TestWriteFileModTimeWithOpenWriters 2026/04/18 05:31:42 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:42 DEBUG : Looking for writers 2026/04/18 05:31:42 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileModTimeWithOpenWriters (1.03s) === RUN TestFileReadAtZeroLength run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:43 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:31:43 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:31:43 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:31:43 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:43 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:31:43 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:31:43 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:43 DEBUG : drime root 'rclone-test-nelezit5dace': File to upload is small (0 bytes), uploading instead of streaming 2026/04/18 05:31:43 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:43 DEBUG : file1: Remove: 2026/04/18 05:31:43 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/18 05:31:43 DEBUG : file1: >Remove: err= write_test.go:360: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:360 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:380 Error: Received unexpected error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." Test: TestFileReadAtZeroLength 2026/04/18 05:31:43 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/04/18 05:31:43 DEBUG : file1: >OpenFile: fd=, err=file does not exist write_test.go:365: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:365 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:380 Error: Received unexpected error: file does not exist Test: TestFileReadAtZeroLength 2026/04/18 05:31:43 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:43 DEBUG : Looking for writers 2026/04/18 05:31:43 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtZeroLength (0.95s) === RUN TestFileReadAtNonZeroLength run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:44 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote 2026/04/18 05:31:44 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 05:31:44 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 05:31:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:44 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 05:31:44 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 05:31:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 05:31:44 DEBUG : drime root 'rclone-test-nelezit5dace': File to upload is small (100 bytes), uploading instead of streaming 2026/04/18 05:31:44 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:44 DEBUG : file1: Remove: 2026/04/18 05:31:44 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/18 05:31:44 DEBUG : file1: >Remove: err= write_test.go:360: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:360 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Received unexpected error: failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." Test: TestFileReadAtNonZeroLength 2026/04/18 05:31:44 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/04/18 05:31:44 DEBUG : file1: >OpenFile: fd=, err=file does not exist write_test.go:365: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:365 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Received unexpected error: file does not exist Test: TestFileReadAtNonZeroLength 2026/04/18 05:31:44 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:44 DEBUG : Looking for writers 2026/04/18 05:31:44 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtNonZeroLength (0.94s) === RUN TestZipManyFiles run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:45 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "flat/f000.txt" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:46 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:46 DEBUG : Looking for writers 2026/04/18 05:31:46 DEBUG : >WaitForWriters: --- FAIL: TestZipManyFiles (2.33s) === RUN TestZipManySubDirs run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:47 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "a/top.txt" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:48 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:48 DEBUG : Looking for writers 2026/04/18 05:31:48 DEBUG : >WaitForWriters: --- FAIL: TestZipManySubDirs (2.49s) === RUN TestZipLargeFiles run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:50 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "bigdir/big.bin" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:51 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:51 DEBUG : Looking for writers 2026/04/18 05:31:51 DEBUG : >WaitForWriters: --- FAIL: TestZipLargeFiles (2.68s) === RUN TestZipDirsInRoot run.go:185: Remote "drime root 'rclone-test-nelezit5dace'", Local "Local file system at /tmp/rclone1297062720", Modify Window "876000h0m0s" 2026/04/18 05:31:52 INFO : drime root 'rclone-test-nelezit5dace': poll-interval is not supported by this remote run.go:290: Failed to put "dir1/a.txt" to "drime root 'rclone-test-nelezit5dace'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/18 05:31:53 DEBUG : WaitForWriters: timeout=30s 2026/04/18 05:31:53 DEBUG : Looking for writers 2026/04/18 05:31:53 DEBUG : >WaitForWriters: --- FAIL: TestZipDirsInRoot (2.21s) FAIL 2026/04/18 05:31:54 DEBUG : drime root 'rclone-test-nelezit5dace': Purge remote "./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCaseSensitivity|TestDirCreate|TestDirFileOpen|TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMetadataExtension|TestDirMethods|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleMethodsWrite|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestRWFileHandleWriteAt|TestRWFileHandleWriteNoWrite|TestRWFileModTimeWithOpenWriters|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleRelease|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters|TestZipDirsInRoot|TestZipLargeFiles|TestZipManyFiles|TestZipManySubDirs)$|^TestFileRename$/^(full,forceCache=false|minimal,forceCache=false|minimal,forceCache=true|off,forceCache=false|writes,forceCache=false|writes,forceCache=true)$|^TestFileSetModTime$/^(cache=full,open=false,write=false|cache=full,open=true,write=false|cache=full,open=true,write=true|cache=off,open=false,write=false|cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Finished ERROR in 7m37.376509271s (try 5/5): exit status 1: Failed [TestDirHandleMethods TestDirHandleReaddir TestDirHandleReaddirnames TestDirMethods TestDirForgetAll TestDirForgetPath TestDirWalk TestDirSetModTime TestDirStat TestDirReadDirAll TestDirOpen TestDirCreate TestDirMkdir TestDirMkdirSub TestDirRemove TestDirRemoveAll TestDirRemoveName TestDirRename TestDirFileOpen TestDirMetadataExtension TestFileMethods TestFileSetModTime/cache=off,open=false,write=false TestFileSetModTime/cache=off,open=true,write=false TestFileSetModTime/cache=off,open=true,write=true TestFileSetModTime/cache=full,open=false,write=false TestFileSetModTime/cache=full,open=true,write=false TestFileSetModTime/cache=full,open=true,write=true TestFileOpenRead TestFileOpenWrite TestFileRemove TestFileRemoveAll TestFileOpen TestFileRename/off,forceCache=false TestFileRename/minimal,forceCache=false TestFileRename/minimal,forceCache=true TestFileRename/writes,forceCache=false TestFileRename/writes,forceCache=true TestFileRename/full,forceCache=false TestReadFileHandleMethods TestReadFileHandleSeek TestReadFileHandleReadAt TestReadFileHandleFlush TestReadFileHandleRelease TestRWFileHandleMethodsRead TestRWFileHandleSeek TestRWFileHandleReadAt TestRWFileHandleFlushRead TestRWFileHandleReleaseRead TestRWFileHandleMethodsWrite TestRWFileHandleWriteAt TestRWFileHandleWriteNoWrite TestRWFileHandleSizeTruncateExisting TestRWFileHandleSizeCreateExisting TestRWFileModTimeWithOpenWriters TestCaseSensitivity TestUnicodeNormalization TestVFSStat TestVFSStatParent TestVFSOpenFile TestVFSRename TestWriteFileHandleMethods TestWriteFileHandleWriteAt TestWriteFileHandleFlush TestWriteFileHandleRelease TestWriteFileModTimeWithOpenWriters TestFileReadAtZeroLength TestFileReadAtNonZeroLength TestZipManyFiles TestZipManySubDirs TestZipLargeFiles TestZipDirsInRoot]