"./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/19 03:52:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-gufunuk4pemu" 2026/04/19 03:52:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/04/19 03:52:11 DEBUG : Creating backend with remote "/tmp/rclone4081266440" === RUN TestDirHandleMethods run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:11 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:13 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:13 DEBUG : Looking for writers 2026/04/19 03:52:13 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleMethods (3.16s) === RUN TestDirHandleReaddir run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:15 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:16 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:16 DEBUG : Looking for writers 2026/04/19 03:52:16 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddir (2.34s) === RUN TestDirHandleReaddirnames run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:17 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:18 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:18 DEBUG : Looking for writers 2026/04/19 03:52:18 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddirnames (2.40s) === RUN TestDirMethods run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:19 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:20 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:20 DEBUG : Looking for writers 2026/04/19 03:52:20 DEBUG : >WaitForWriters: --- FAIL: TestDirMethods (2.45s) === RUN TestDirForgetAll run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:22 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:23 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:23 DEBUG : Looking for writers 2026/04/19 03:52:23 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetAll (2.42s) === RUN TestDirForgetPath run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:24 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:25 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:25 DEBUG : Looking for writers 2026/04/19 03:52:25 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetPath (2.32s) === RUN TestDirWalk run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:27 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:28 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:28 DEBUG : Looking for writers 2026/04/19 03:52:28 DEBUG : >WaitForWriters: --- FAIL: TestDirWalk (2.50s) === RUN TestDirSetModTime run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:29 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:30 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:30 DEBUG : Looking for writers 2026/04/19 03:52:30 DEBUG : >WaitForWriters: --- FAIL: TestDirSetModTime (2.35s) === RUN TestDirStat run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:31 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:32 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:32 DEBUG : Looking for writers 2026/04/19 03:52:32 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/dir_test.go:25 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:229 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestDirStat Messages: directories --- FAIL: TestDirStat (9.97s) === RUN TestDirReadDirAll run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:41 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:43 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:43 DEBUG : Looking for writers 2026/04/19 03:52:43 DEBUG : >WaitForWriters: --- FAIL: TestDirReadDirAll (2.70s) === RUN TestDirOpen run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:44 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:45 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:45 DEBUG : Looking for writers 2026/04/19 03:52:45 DEBUG : >WaitForWriters: --- FAIL: TestDirOpen (2.49s) === RUN TestDirCreate run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:47 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:47 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:47 DEBUG : Looking for writers 2026/04/19 03:52:47 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (2.30s) === RUN TestDirMkdir run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:49 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:50 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:50 DEBUG : Looking for writers 2026/04/19 03:52:50 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdir (2.40s) === RUN TestDirMkdirSub run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:51 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:52 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:52 DEBUG : Looking for writers 2026/04/19 03:52:52 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdirSub (2.35s) === RUN TestDirRemove run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:54 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:54 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:54 DEBUG : Looking for writers 2026/04/19 03:52:54 DEBUG : >WaitForWriters: --- FAIL: TestDirRemove (2.46s) === RUN TestDirRemoveAll run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:56 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:52:57 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:52:57 DEBUG : Looking for writers 2026/04/19 03:52:57 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveAll (2.32s) === RUN TestDirRemoveName run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:52:58 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:00 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:00 DEBUG : Looking for writers 2026/04/19 03:53:00 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (2.75s) === RUN TestDirRename run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:01 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:02 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:02 DEBUG : Looking for writers 2026/04/19 03:53:02 DEBUG : >WaitForWriters: --- FAIL: TestDirRename (2.34s) === RUN TestDirFileOpen run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:04 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:04 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:04 DEBUG : Looking for writers 2026/04/19 03:53:04 DEBUG : >WaitForWriters: --- FAIL: TestDirFileOpen (2.33s) === RUN TestDirMetadataExtension run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:06 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:07 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:07 DEBUG : Looking for writers 2026/04/19 03:53:07 DEBUG : >WaitForWriters: --- FAIL: TestDirMetadataExtension (2.53s) === RUN TestFileMethods run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:08 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:09 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:09 DEBUG : Looking for writers 2026/04/19 03:53:09 DEBUG : >WaitForWriters: --- FAIL: TestFileMethods (2.41s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:11 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:12 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:12 DEBUG : Looking for writers 2026/04/19 03:53:12 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:13 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:14 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:14 DEBUG : Looking for writers 2026/04/19 03:53:14 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:16 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:17 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:17 DEBUG : Looking for writers 2026/04/19 03:53:17 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:18 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:53:18 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:53:18 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:18 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:18 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:18 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:18 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:19 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:19 DEBUG : Looking for writers 2026/04/19 03:53:19 DEBUG : >WaitForWriters: 2026/04/19 03:53:19 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:21 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:53:21 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:53:21 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:21 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:21 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:22 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:22 DEBUG : Looking for writers 2026/04/19 03:53:22 DEBUG : >WaitForWriters: 2026/04/19 03:53:22 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:23 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:53:23 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:53:23 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:23 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:23 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:24 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:24 DEBUG : Looking for writers 2026/04/19 03:53:24 DEBUG : >WaitForWriters: 2026/04/19 03:53:24 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestFileSetModTime (14.94s) --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (2.45s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=false (2.40s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (2.72s) --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (2.32s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (2.54s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (2.51s) === RUN TestFileOpenRead run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:26 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:27 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:27 DEBUG : Looking for writers 2026/04/19 03:53:27 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenRead (2.40s) === RUN TestFileOpenWrite run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:28 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:29 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:29 DEBUG : Looking for writers 2026/04/19 03:53:29 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenWrite (2.66s) === RUN TestFileRemove run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:31 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:32 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:32 DEBUG : Looking for writers 2026/04/19 03:53:32 DEBUG : >WaitForWriters: --- FAIL: TestFileRemove (2.37s) === RUN TestFileRemoveAll run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:33 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:34 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:34 DEBUG : Looking for writers 2026/04/19 03:53:34 DEBUG : >WaitForWriters: --- FAIL: TestFileRemoveAll (2.40s) === RUN TestFileOpen run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:36 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:36 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:36 DEBUG : Looking for writers 2026/04/19 03:53:36 DEBUG : >WaitForWriters: --- FAIL: TestFileOpen (2.48s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:38 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:39 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:39 DEBUG : Looking for writers 2026/04/19 03:53:39 DEBUG : >WaitForWriters: === RUN TestFileRename/minimal,forceCache=false run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:41 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:53:41 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:53:41 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:41 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:41 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:41 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:41 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:41 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:41 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:41 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:41 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:41 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:41 DEBUG : Looking for writers 2026/04/19 03:53:41 DEBUG : >WaitForWriters: 2026/04/19 03:53:41 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting === RUN TestFileRename/minimal,forceCache=true run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:43 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:53:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:53:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:43 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:43 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:43 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:43 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:43 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:43 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:43 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:44 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:44 DEBUG : Looking for writers 2026/04/19 03:53:44 DEBUG : >WaitForWriters: 2026/04/19 03:53:44 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=false run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:45 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:53:45 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:53:45 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:45 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:45 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:45 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:45 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:46 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:46 DEBUG : Looking for writers 2026/04/19 03:53:46 DEBUG : >WaitForWriters: 2026/04/19 03:53:46 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=true run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:48 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:53:48 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:53:48 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:48 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:48 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:48 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:48 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:48 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:48 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:48 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:48 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:49 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:49 DEBUG : Looking for writers 2026/04/19 03:53:49 DEBUG : >WaitForWriters: 2026/04/19 03:53:49 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting === RUN TestFileRename/full,forceCache=false run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:51 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:53:51 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:53:51 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:51 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:51 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:51 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:51 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:51 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:51 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:53:51 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:53:51 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:51 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:51 DEBUG : Looking for writers 2026/04/19 03:53:51 DEBUG : >WaitForWriters: 2026/04/19 03:53:51 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestFileRename (14.89s) --- FAIL: TestFileRename/off,forceCache=false (2.49s) --- FAIL: TestFileRename/minimal,forceCache=false (2.49s) --- FAIL: TestFileRename/minimal,forceCache=true (2.45s) --- FAIL: TestFileRename/writes,forceCache=false (2.74s) --- FAIL: TestFileRename/writes,forceCache=true (2.40s) --- FAIL: TestFileRename/full,forceCache=false (2.33s) === RUN TestReadFileHandleMethods run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:53 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:54 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:54 DEBUG : Looking for writers 2026/04/19 03:53:54 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (2.54s) === RUN TestReadFileHandleSeek run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:55 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:56 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:56 DEBUG : Looking for writers 2026/04/19 03:53:56 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleSeek (2.40s) === RUN TestReadFileHandleReadAt run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:53:58 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:53:59 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:53:59 DEBUG : Looking for writers 2026/04/19 03:53:59 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (2.45s) === RUN TestReadFileHandleFlush run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:54:00 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:54:01 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:54:01 DEBUG : Looking for writers 2026/04/19 03:54:01 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleFlush (2.51s) === RUN TestReadFileHandleRelease run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:54:03 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:54:04 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:54:04 DEBUG : Looking for writers 2026/04/19 03:54:04 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (2.58s) === RUN TestRWFileHandleMethodsRead run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:54:05 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:54:05 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:54:05 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:05 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:05 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:05 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:05 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:05 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:05 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:05 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:05 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:54:06 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:54:06 DEBUG : Looking for writers 2026/04/19 03:54:06 DEBUG : >WaitForWriters: 2026/04/19 03:54:06 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (2.37s) === RUN TestRWFileHandleSeek run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:54:08 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:54:08 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:54:08 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:08 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:08 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:08 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:08 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:08 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:08 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:08 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:08 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:54:09 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:54:09 DEBUG : Looking for writers 2026/04/19 03:54:09 DEBUG : >WaitForWriters: 2026/04/19 03:54:09 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (2.45s) === RUN TestRWFileHandleReadAt run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:54:10 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:54:10 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:54:10 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:10 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:10 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:10 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:10 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:10 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:10 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:10 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:10 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:54:11 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:54:11 DEBUG : Looking for writers 2026/04/19 03:54:11 DEBUG : >WaitForWriters: 2026/04/19 03:54:11 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (2.51s) === RUN TestRWFileHandleFlushRead run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:54:13 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:54:13 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:54:13 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:13 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:13 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:13 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:13 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:13 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:13 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:13 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:13 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:54:14 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:54:14 DEBUG : Looking for writers 2026/04/19 03:54:14 DEBUG : >WaitForWriters: 2026/04/19 03:54:14 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting 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_write_test.go:39 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:216 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestRWFileHandleFlushRead Messages: directories --- FAIL: TestRWFileHandleFlushRead (10.01s) === RUN TestRWFileHandleReleaseRead run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:54:23 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:54:23 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:54:23 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:23 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:23 DEBUG : Config file has changed externally - reloading 2026/04/19 03:54:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:23 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:54:24 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:54:24 DEBUG : Looking for writers 2026/04/19 03:54:24 DEBUG : >WaitForWriters: 2026/04/19 03:54:24 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (2.62s) === RUN TestRWFileHandleMethodsWrite run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:54:25 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:54:25 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:54:25 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:25 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:25 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:25 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:25 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:25 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:25 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:54:25 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:54:25 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:54:25 INFO : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/19 03:54:26 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:54:26 DEBUG : file1: newRWFileHandle: 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): openPending: 2026/04/19 03:54:26 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/19 03:54:26 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): >openPending: err= 2026/04/19 03:54:26 DEBUG : file1: >newRWFileHandle: err= 2026/04/19 03:54:26 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:54:26 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/19 03:54:26 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): _writeAt: size=5, off=0 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): >_writeAt: n=5, err= 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): _writeAt: size=7, off=5 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): >_writeAt: n=7, err= 2026/04/19 03:54:26 DEBUG : file1: vfs cache: truncate to size=11 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): close: 2026/04/19 03:54:26 DEBUG : file1: vfs cache: setting modification time to 2026-04-19 03:54:26.087449611 +0000 UTC m=+135.169060686 2026/04/19 03:54:26 INFO : file1: vfs cache: queuing for upload in 100ms 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): >close: err= 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): close: 2026/04/19 03:54:26 DEBUG : file1(0x23e69fa8c340): >close: err=file already closed 2026/04/19 03:54:26 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:54:26 DEBUG : Looking for writers 2026/04/19 03:54:26 DEBUG : file1: reading active writers 2026/04/19 03:54:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/19 03:54:26 DEBUG : Looking for writers 2026/04/19 03:54:26 DEBUG : file1: reading active writers 2026/04/19 03:54:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/19 03:54:26 DEBUG : Looking for writers 2026/04/19 03:54:26 DEBUG : file1: reading active writers 2026/04/19 03:54:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/19 03:54:26 DEBUG : Looking for writers 2026/04/19 03:54:26 DEBUG : file1: reading active writers 2026/04/19 03:54:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/19 03:54:26 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:54:26 DEBUG : Looking for writers 2026/04/19 03:54:26 DEBUG : file1: reading active writers 2026/04/19 03:54:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/19 03:54:26 DEBUG : Looking for writers 2026/04/19 03:54:26 DEBUG : file1: reading active writers 2026/04/19 03:54:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/19 03:54:26 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/19 03:54:26 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/19 03:54:26 DEBUG : Looking for writers 2026/04/19 03:54:26 DEBUG : file1: reading active writers 2026/04/19 03:54:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/19 03:54:26 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:54: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/19 03:54:27 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/19 03:54:27 DEBUG : Looking for writers 2026/04/19 03:54:27 DEBUG : file1: reading active writers 2026/04/19 03:54:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:27 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:54: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/19 03:54:27 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/19 03:54:28 DEBUG : Looking for writers 2026/04/19 03:54:28 DEBUG : file1: reading active writers 2026/04/19 03:54:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:28 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:54:29 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/19 03:54:29 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/19 03:54:29 DEBUG : Looking for writers 2026/04/19 03:54:29 DEBUG : file1: reading active writers 2026/04/19 03:54:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:30 DEBUG : Looking for writers 2026/04/19 03:54:30 DEBUG : file1: reading active writers 2026/04/19 03:54:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:30 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:54:31 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/19 03:54:31 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/19 03:54:31 DEBUG : Looking for writers 2026/04/19 03:54:31 DEBUG : file1: reading active writers 2026/04/19 03:54:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:32 DEBUG : Looking for writers 2026/04/19 03:54:32 DEBUG : file1: reading active writers 2026/04/19 03:54:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:33 DEBUG : Looking for writers 2026/04/19 03:54:33 DEBUG : file1: reading active writers 2026/04/19 03:54:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:34 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:54:34 DEBUG : Looking for writers 2026/04/19 03:54:34 DEBUG : file1: reading active writers 2026/04/19 03:54:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:34 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/19 03:54:34 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/19 03:54:35 DEBUG : Looking for writers 2026/04/19 03:54:35 DEBUG : file1: reading active writers 2026/04/19 03:54:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:36 DEBUG : Looking for writers 2026/04/19 03:54:36 DEBUG : file1: reading active writers 2026/04/19 03:54:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:37 DEBUG : Looking for writers 2026/04/19 03:54:37 DEBUG : file1: reading active writers 2026/04/19 03:54:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:38 DEBUG : Looking for writers 2026/04/19 03:54:38 DEBUG : file1: reading active writers 2026/04/19 03:54:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:39 DEBUG : Looking for writers 2026/04/19 03:54:39 DEBUG : file1: reading active writers 2026/04/19 03:54:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:40 DEBUG : Looking for writers 2026/04/19 03:54:40 DEBUG : file1: reading active writers 2026/04/19 03:54:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:41 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:54:41 DEBUG : Looking for writers 2026/04/19 03:54:41 DEBUG : file1: reading active writers 2026/04/19 03:54:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54: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/19 03:54:41 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/19 03:54:42 DEBUG : Looking for writers 2026/04/19 03:54:42 DEBUG : file1: reading active writers 2026/04/19 03:54:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:43 DEBUG : Looking for writers 2026/04/19 03:54:43 DEBUG : file1: reading active writers 2026/04/19 03:54:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:44 DEBUG : Looking for writers 2026/04/19 03:54:44 DEBUG : file1: reading active writers 2026/04/19 03:54:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:45 DEBUG : Looking for writers 2026/04/19 03:54:45 DEBUG : file1: reading active writers 2026/04/19 03:54:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:46 DEBUG : Looking for writers 2026/04/19 03:54:46 DEBUG : file1: reading active writers 2026/04/19 03:54:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:47 DEBUG : Looking for writers 2026/04/19 03:54:47 DEBUG : file1: reading active writers 2026/04/19 03:54:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:48 DEBUG : Looking for writers 2026/04/19 03:54:48 DEBUG : file1: reading active writers 2026/04/19 03:54:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:49 DEBUG : Looking for writers 2026/04/19 03:54:49 DEBUG : file1: reading active writers 2026/04/19 03:54:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:50 DEBUG : Looking for writers 2026/04/19 03:54:50 DEBUG : file1: reading active writers 2026/04/19 03:54:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:51 DEBUG : Looking for writers 2026/04/19 03:54:51 DEBUG : file1: reading active writers 2026/04/19 03:54:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:52 DEBUG : Looking for writers 2026/04/19 03:54:52 DEBUG : file1: reading active writers 2026/04/19 03:54:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:53 DEBUG : Looking for writers 2026/04/19 03:54:53 DEBUG : file1: reading active writers 2026/04/19 03:54:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:54 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:54:54 DEBUG : Looking for writers 2026/04/19 03:54:54 DEBUG : file1: reading active writers 2026/04/19 03:54:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54: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/19 03:54:54 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/19 03:54:55 DEBUG : Looking for writers 2026/04/19 03:54:55 DEBUG : file1: reading active writers 2026/04/19 03:54:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:54:56 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x23e69fbfec00 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69fd58488 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473431020736} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14011035288679637003 ext:135169060686 loc:0x46a1880} ATime:{wall:14011035288679665327 ext:135169089009 loc:0x46a1880} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/19 03:54:56 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/19 03:55:04 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:55:04 DEBUG : Looking for writers 2026/04/19 03:55:04 DEBUG : file1: reading active writers 2026/04/19 03:55:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/19 03:55:04 DEBUG : Looking for writers 2026/04/19 03:55:04 DEBUG : file1: reading active writers 2026/04/19 03:55:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/19 03:55:04 DEBUG : Looking for writers 2026/04/19 03:55:04 DEBUG : file1: reading active writers 2026/04/19 03:55:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/19 03:55:04 DEBUG : Looking for writers 2026/04/19 03:55:04 DEBUG : file1: reading active writers 2026/04/19 03:55:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/19 03:55:04 DEBUG : Looking for writers 2026/04/19 03:55:04 DEBUG : file1: reading active writers 2026/04/19 03:55:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/19 03:55:04 DEBUG : Looking for writers 2026/04/19 03:55:04 DEBUG : file1: reading active writers 2026/04/19 03:55:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/19 03:55:05 DEBUG : Looking for writers 2026/04/19 03:55:05 DEBUG : file1: reading active writers 2026/04/19 03:55:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/19 03:55:05 DEBUG : Looking for writers 2026/04/19 03:55:05 DEBUG : file1: reading active writers 2026/04/19 03:55:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:06 DEBUG : Looking for writers 2026/04/19 03:55:06 DEBUG : file1: reading active writers 2026/04/19 03:55:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:07 DEBUG : Looking for writers 2026/04/19 03:55:07 DEBUG : file1: reading active writers 2026/04/19 03:55:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:08 DEBUG : Looking for writers 2026/04/19 03:55:08 DEBUG : file1: reading active writers 2026/04/19 03:55:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:09 DEBUG : Looking for writers 2026/04/19 03:55:09 DEBUG : file1: reading active writers 2026/04/19 03:55:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:10 DEBUG : Looking for writers 2026/04/19 03:55:10 DEBUG : file1: reading active writers 2026/04/19 03:55:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:11 DEBUG : Looking for writers 2026/04/19 03:55:11 DEBUG : file1: reading active writers 2026/04/19 03:55:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:12 DEBUG : Looking for writers 2026/04/19 03:55:12 DEBUG : file1: reading active writers 2026/04/19 03:55:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:13 DEBUG : Looking for writers 2026/04/19 03:55:13 DEBUG : file1: reading active writers 2026/04/19 03:55:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:14 DEBUG : Looking for writers 2026/04/19 03:55:14 DEBUG : file1: reading active writers 2026/04/19 03:55:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:15 DEBUG : Looking for writers 2026/04/19 03:55:15 DEBUG : file1: reading active writers 2026/04/19 03:55:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:16 DEBUG : Looking for writers 2026/04/19 03:55:16 DEBUG : file1: reading active writers 2026/04/19 03:55:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:17 DEBUG : Looking for writers 2026/04/19 03:55:17 DEBUG : file1: reading active writers 2026/04/19 03:55:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:18 DEBUG : Looking for writers 2026/04/19 03:55:18 DEBUG : file1: reading active writers 2026/04/19 03:55:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:19 DEBUG : Looking for writers 2026/04/19 03:55:19 DEBUG : file1: reading active writers 2026/04/19 03:55:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:20 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:55:20 DEBUG : Looking for writers 2026/04/19 03:55:20 DEBUG : file1: reading active writers 2026/04/19 03:55:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:21 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/19 03:55:21 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/19 03:55:21 DEBUG : Looking for writers 2026/04/19 03:55:21 DEBUG : file1: reading active writers 2026/04/19 03:55:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:22 DEBUG : Looking for writers 2026/04/19 03:55:22 DEBUG : file1: reading active writers 2026/04/19 03:55:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:23 DEBUG : Looking for writers 2026/04/19 03:55:23 DEBUG : file1: reading active writers 2026/04/19 03:55:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:24 DEBUG : Looking for writers 2026/04/19 03:55:24 DEBUG : file1: reading active writers 2026/04/19 03:55:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:25 DEBUG : Looking for writers 2026/04/19 03:55:25 DEBUG : file1: reading active writers 2026/04/19 03:55:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:25 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2026/04/19 03:55:25 INFO : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11) 2026/04/19 03:55:26 DEBUG : Looking for writers 2026/04/19 03:55:26 DEBUG : file1: reading active writers 2026/04/19 03:55:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:27 DEBUG : Looking for writers 2026/04/19 03:55:27 DEBUG : file1: reading active writers 2026/04/19 03:55:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:28 DEBUG : Looking for writers 2026/04/19 03:55:28 DEBUG : file1: reading active writers 2026/04/19 03:55:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:29 DEBUG : Looking for writers 2026/04/19 03:55:29 DEBUG : file1: reading active writers 2026/04/19 03:55:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:30 DEBUG : Looking for writers 2026/04/19 03:55:30 DEBUG : file1: reading active writers 2026/04/19 03:55:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:31 DEBUG : Looking for writers 2026/04/19 03:55:31 DEBUG : file1: reading active writers 2026/04/19 03:55:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:32 DEBUG : Looking for writers 2026/04/19 03:55:32 DEBUG : file1: reading active writers 2026/04/19 03:55:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:33 DEBUG : Looking for writers 2026/04/19 03:55:33 DEBUG : file1: reading active writers 2026/04/19 03:55:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:34 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x23e69fbfec00 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69fd58488 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473431020736} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14011035288679637003 ext:135169060686 loc:0x46a1880} ATime:{wall:14011035288679665327 ext:135169089009 loc:0x46a1880} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/19 03:55:34 DEBUG : >WaitForWriters: 2026/04/19 03:55:34 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsWrite (68.98s) === RUN TestRWFileHandleWriteAt run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:55:34 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:55:34 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:55:34 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:55:34 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:55:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:55:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:55:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:55:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:55:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:55:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:55:34 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:55:34 INFO : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/19 03:55:35 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:55:35 DEBUG : file1: newRWFileHandle: 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): openPending: 2026/04/19 03:55:35 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/19 03:55:35 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): >openPending: err= 2026/04/19 03:55:35 DEBUG : file1: >newRWFileHandle: err= 2026/04/19 03:55:35 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:55:35 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/19 03:55:35 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): _writeAt: size=7, off=0 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): >_writeAt: n=7, err= 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): _writeAt: size=6, off=5 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): >_writeAt: n=6, err= 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): close: 2026/04/19 03:55:35 DEBUG : file1: vfs cache: setting modification time to 2026-04-19 03:55:35.053158272 +0000 UTC m=+204.134769346 2026/04/19 03:55:35 INFO : file1: vfs cache: queuing for upload in 100ms 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): >close: err= 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): _writeAt: size=5, off=0 2026/04/19 03:55:35 DEBUG : file1(0x23e69f74a700): >_writeAt: n=0, err=file already closed 2026/04/19 03:55:35 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:55:35 DEBUG : Looking for writers 2026/04/19 03:55:35 DEBUG : file1: reading active writers 2026/04/19 03:55:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/19 03:55:35 DEBUG : Looking for writers 2026/04/19 03:55:35 DEBUG : file1: reading active writers 2026/04/19 03:55:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/19 03:55:35 DEBUG : Looking for writers 2026/04/19 03:55:35 DEBUG : file1: reading active writers 2026/04/19 03:55:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/19 03:55:35 DEBUG : Looking for writers 2026/04/19 03:55:35 DEBUG : file1: reading active writers 2026/04/19 03:55:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/19 03:55:35 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:55:35 DEBUG : Looking for writers 2026/04/19 03:55:35 DEBUG : file1: reading active writers 2026/04/19 03:55:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/19 03:55:35 DEBUG : Looking for writers 2026/04/19 03:55:35 DEBUG : file1: reading active writers 2026/04/19 03:55:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/19 03:55: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/19 03:55:35 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/19 03:55:35 DEBUG : Looking for writers 2026/04/19 03:55:35 DEBUG : file1: reading active writers 2026/04/19 03:55:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/19 03:55:35 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:55:36 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/19 03:55:36 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/19 03:55:36 DEBUG : Looking for writers 2026/04/19 03:55:36 DEBUG : file1: reading active writers 2026/04/19 03:55:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:36 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:55:36 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/19 03:55:36 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/19 03:55:37 DEBUG : Looking for writers 2026/04/19 03:55:37 DEBUG : file1: reading active writers 2026/04/19 03:55:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:37 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:55:38 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/19 03:55:38 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/19 03:55:38 DEBUG : Looking for writers 2026/04/19 03:55:38 DEBUG : file1: reading active writers 2026/04/19 03:55:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:39 DEBUG : Looking for writers 2026/04/19 03:55:39 DEBUG : file1: reading active writers 2026/04/19 03:55:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:39 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:55: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/19 03:55:40 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/19 03:55:40 DEBUG : Looking for writers 2026/04/19 03:55:40 DEBUG : file1: reading active writers 2026/04/19 03:55:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:41 DEBUG : Looking for writers 2026/04/19 03:55:41 DEBUG : file1: reading active writers 2026/04/19 03:55:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:42 DEBUG : Looking for writers 2026/04/19 03:55:42 DEBUG : file1: reading active writers 2026/04/19 03:55:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:43 DEBUG : Looking for writers 2026/04/19 03:55:43 DEBUG : file1: reading active writers 2026/04/19 03:55:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:43 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:55: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/19 03:55:43 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/19 03:55:44 DEBUG : Looking for writers 2026/04/19 03:55:44 DEBUG : file1: reading active writers 2026/04/19 03:55:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:45 DEBUG : Looking for writers 2026/04/19 03:55:45 DEBUG : file1: reading active writers 2026/04/19 03:55:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:46 DEBUG : Looking for writers 2026/04/19 03:55:46 DEBUG : file1: reading active writers 2026/04/19 03:55:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:47 DEBUG : Looking for writers 2026/04/19 03:55:47 DEBUG : file1: reading active writers 2026/04/19 03:55:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:48 DEBUG : Looking for writers 2026/04/19 03:55:48 DEBUG : file1: reading active writers 2026/04/19 03:55:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:49 DEBUG : Looking for writers 2026/04/19 03:55:49 DEBUG : file1: reading active writers 2026/04/19 03:55:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:50 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:55:50 DEBUG : Looking for writers 2026/04/19 03:55:50 DEBUG : file1: reading active writers 2026/04/19 03:55:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55: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/19 03:55:50 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/19 03:55:51 DEBUG : Looking for writers 2026/04/19 03:55:51 DEBUG : file1: reading active writers 2026/04/19 03:55:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:52 DEBUG : Looking for writers 2026/04/19 03:55:52 DEBUG : file1: reading active writers 2026/04/19 03:55:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:53 DEBUG : Looking for writers 2026/04/19 03:55:53 DEBUG : file1: reading active writers 2026/04/19 03:55:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:54 DEBUG : Looking for writers 2026/04/19 03:55:54 DEBUG : file1: reading active writers 2026/04/19 03:55:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:55 DEBUG : Looking for writers 2026/04/19 03:55:55 DEBUG : file1: reading active writers 2026/04/19 03:55:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:56 DEBUG : Looking for writers 2026/04/19 03:55:56 DEBUG : file1: reading active writers 2026/04/19 03:55:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:57 DEBUG : Looking for writers 2026/04/19 03:55:57 DEBUG : file1: reading active writers 2026/04/19 03:55:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:58 DEBUG : Looking for writers 2026/04/19 03:55:58 DEBUG : file1: reading active writers 2026/04/19 03:55:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:55:59 DEBUG : Looking for writers 2026/04/19 03:55:59 DEBUG : file1: reading active writers 2026/04/19 03:55:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:00 DEBUG : Looking for writers 2026/04/19 03:56:00 DEBUG : file1: reading active writers 2026/04/19 03:56:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:01 DEBUG : Looking for writers 2026/04/19 03:56:01 DEBUG : file1: reading active writers 2026/04/19 03:56:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:02 DEBUG : Looking for writers 2026/04/19 03:56:02 DEBUG : file1: reading active writers 2026/04/19 03:56:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:03 DEBUG : Looking for writers 2026/04/19 03:56:03 DEBUG : file1: reading active writers 2026/04/19 03:56:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:03 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56: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/19 03:56:03 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/19 03:56:04 DEBUG : Looking for writers 2026/04/19 03:56:04 DEBUG : file1: reading active writers 2026/04/19 03:56:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:05 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x23e69fbff000 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69fc35328 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473429828448} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14011035362733531520 ext:204134769346 loc:0x46a1880} ATime:{wall:14011035362733553210 ext:204134791046 loc:0x46a1880} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/19 03:56:05 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/19 03:56:13 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:56:13 DEBUG : Looking for writers 2026/04/19 03:56:13 DEBUG : file1: reading active writers 2026/04/19 03:56:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/19 03:56:13 DEBUG : Looking for writers 2026/04/19 03:56:13 DEBUG : file1: reading active writers 2026/04/19 03:56:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/19 03:56:13 DEBUG : Looking for writers 2026/04/19 03:56:13 DEBUG : file1: reading active writers 2026/04/19 03:56:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/19 03:56:13 DEBUG : Looking for writers 2026/04/19 03:56:13 DEBUG : file1: reading active writers 2026/04/19 03:56:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/19 03:56:13 DEBUG : Looking for writers 2026/04/19 03:56:13 DEBUG : file1: reading active writers 2026/04/19 03:56:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/19 03:56:13 DEBUG : Looking for writers 2026/04/19 03:56:13 DEBUG : file1: reading active writers 2026/04/19 03:56:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/19 03:56:14 DEBUG : Looking for writers 2026/04/19 03:56:14 DEBUG : file1: reading active writers 2026/04/19 03:56:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/19 03:56:14 DEBUG : Looking for writers 2026/04/19 03:56:14 DEBUG : file1: reading active writers 2026/04/19 03:56:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:15 DEBUG : Looking for writers 2026/04/19 03:56:15 DEBUG : file1: reading active writers 2026/04/19 03:56:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:16 DEBUG : Looking for writers 2026/04/19 03:56:16 DEBUG : file1: reading active writers 2026/04/19 03:56:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:17 DEBUG : Looking for writers 2026/04/19 03:56:17 DEBUG : file1: reading active writers 2026/04/19 03:56:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:18 DEBUG : Looking for writers 2026/04/19 03:56:18 DEBUG : file1: reading active writers 2026/04/19 03:56:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:19 DEBUG : Looking for writers 2026/04/19 03:56:19 DEBUG : file1: reading active writers 2026/04/19 03:56:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:20 DEBUG : Looking for writers 2026/04/19 03:56:20 DEBUG : file1: reading active writers 2026/04/19 03:56:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:21 DEBUG : Looking for writers 2026/04/19 03:56:21 DEBUG : file1: reading active writers 2026/04/19 03:56:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:22 DEBUG : Looking for writers 2026/04/19 03:56:22 DEBUG : file1: reading active writers 2026/04/19 03:56:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:23 DEBUG : Looking for writers 2026/04/19 03:56:23 DEBUG : file1: reading active writers 2026/04/19 03:56:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:24 DEBUG : Looking for writers 2026/04/19 03:56:24 DEBUG : file1: reading active writers 2026/04/19 03:56:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:25 DEBUG : Looking for writers 2026/04/19 03:56:25 DEBUG : file1: reading active writers 2026/04/19 03:56:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:26 DEBUG : Looking for writers 2026/04/19 03:56:26 DEBUG : file1: reading active writers 2026/04/19 03:56:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:27 DEBUG : Looking for writers 2026/04/19 03:56:27 DEBUG : file1: reading active writers 2026/04/19 03:56:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:28 DEBUG : Looking for writers 2026/04/19 03:56:28 DEBUG : file1: reading active writers 2026/04/19 03:56:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:29 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56:29 DEBUG : Looking for writers 2026/04/19 03:56:29 DEBUG : file1: reading active writers 2026/04/19 03:56:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:30 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/19 03:56:30 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/19 03:56:30 DEBUG : Looking for writers 2026/04/19 03:56:30 DEBUG : file1: reading active writers 2026/04/19 03:56:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:31 DEBUG : Looking for writers 2026/04/19 03:56:31 DEBUG : file1: reading active writers 2026/04/19 03:56:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:32 DEBUG : Looking for writers 2026/04/19 03:56:32 DEBUG : file1: reading active writers 2026/04/19 03:56:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:33 DEBUG : Looking for writers 2026/04/19 03:56:33 DEBUG : file1: reading active writers 2026/04/19 03:56:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:34 DEBUG : Looking for writers 2026/04/19 03:56:34 DEBUG : file1: reading active writers 2026/04/19 03:56:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:34 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2026/04/19 03:56:34 INFO : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11) 2026/04/19 03:56:35 DEBUG : Looking for writers 2026/04/19 03:56:35 DEBUG : file1: reading active writers 2026/04/19 03:56:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:36 DEBUG : Looking for writers 2026/04/19 03:56:36 DEBUG : file1: reading active writers 2026/04/19 03:56:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:37 DEBUG : Looking for writers 2026/04/19 03:56:37 DEBUG : file1: reading active writers 2026/04/19 03:56:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:38 DEBUG : Looking for writers 2026/04/19 03:56:38 DEBUG : file1: reading active writers 2026/04/19 03:56:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:39 DEBUG : Looking for writers 2026/04/19 03:56:39 DEBUG : file1: reading active writers 2026/04/19 03:56:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:40 DEBUG : Looking for writers 2026/04/19 03:56:40 DEBUG : file1: reading active writers 2026/04/19 03:56:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:41 DEBUG : Looking for writers 2026/04/19 03:56:41 DEBUG : file1: reading active writers 2026/04/19 03:56:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:42 DEBUG : Looking for writers 2026/04/19 03:56:42 DEBUG : file1: reading active writers 2026/04/19 03:56:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:56:43 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x23e69fbff000 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69fc35328 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473429828448} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14011035362733531520 ext:204134769346 loc:0x46a1880} ATime:{wall:14011035362733553210 ext:204134791046 loc:0x46a1880} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/19 03:56:43 DEBUG : >WaitForWriters: 2026/04/19 03:56:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteAt (68.97s) === RUN TestRWFileHandleWriteNoWrite run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:56:43 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:56:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:56:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:56:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:56:43 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:56:43 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:56:43 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:56:43 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:56:43 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:56:43 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:56:43 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:56:43 INFO : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/19 03:56:44 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:56:44 DEBUG : file1: newRWFileHandle: 2026/04/19 03:56:44 DEBUG : file1(0x23e69f986500): openPending: 2026/04/19 03:56:44 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/19 03:56:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:56:44 DEBUG : file1(0x23e69f986500): >openPending: err= 2026/04/19 03:56:44 DEBUG : file1: >newRWFileHandle: err= 2026/04/19 03:56:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:56:44 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/19 03:56:44 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/04/19 03:56:44 DEBUG : file1(0x23e69f986500): close: 2026/04/19 03:56:44 DEBUG : file1: vfs cache: setting modification time to 2026-04-19 03:56:44.001198831 +0000 UTC m=+273.082809915 2026/04/19 03:56:44 INFO : file1: vfs cache: queuing for upload in 100ms 2026/04/19 03:56:44 DEBUG : file1(0x23e69f986500): >close: err= 2026/04/19 03:56:44 DEBUG : file2: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2026/04/19 03:56:44 DEBUG : file2: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2026/04/19 03:56:44 DEBUG : file2: newRWFileHandle: 2026/04/19 03:56:44 DEBUG : file2(0x23e69f986cc0): openPending: 2026/04/19 03:56:44 DEBUG : file2: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/19 03:56:44 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/04/19 03:56:44 DEBUG : file2(0x23e69f986cc0): >openPending: err= 2026/04/19 03:56:44 DEBUG : file2: >newRWFileHandle: err= 2026/04/19 03:56:44 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/04/19 03:56:44 DEBUG : file2: >Open: fd=file2 (rw), err= 2026/04/19 03:56:44 DEBUG : file2: >OpenFile: fd=file2 (rw), err= 2026/04/19 03:56:44 DEBUG : file2(0x23e69f986cc0): RWFileHandle.Flush 2026/04/19 03:56:44 DEBUG : file2(0x23e69f986cc0): RWFileHandle.Release 2026/04/19 03:56:44 DEBUG : file2(0x23e69f986cc0): close: 2026/04/19 03:56:44 DEBUG : file2: vfs cache: setting modification time to 2026-04-19 03:56:44.002152673 +0000 UTC m=+273.083763757 2026/04/19 03:56:44 INFO : file2: vfs cache: queuing for upload in 100ms 2026/04/19 03:56:44 DEBUG : file2(0x23e69f986cc0): >close: err= 2026/04/19 03:56:44 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:56:44 DEBUG : Looking for writers 2026/04/19 03:56:44 DEBUG : file1: reading active writers 2026/04/19 03:56:44 DEBUG : file2: reading active writers 2026/04/19 03:56:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 10ms 2026/04/19 03:56:44 DEBUG : Looking for writers 2026/04/19 03:56:44 DEBUG : file1: reading active writers 2026/04/19 03:56:44 DEBUG : file2: reading active writers 2026/04/19 03:56:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 20ms 2026/04/19 03:56:44 DEBUG : Looking for writers 2026/04/19 03:56:44 DEBUG : file1: reading active writers 2026/04/19 03:56:44 DEBUG : file2: reading active writers 2026/04/19 03:56:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 40ms 2026/04/19 03:56:44 DEBUG : Looking for writers 2026/04/19 03:56:44 DEBUG : file1: reading active writers 2026/04/19 03:56:44 DEBUG : file2: reading active writers 2026/04/19 03:56:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 80ms 2026/04/19 03:56:44 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:56:44 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56:44 DEBUG : Looking for writers 2026/04/19 03:56:44 DEBUG : file1: reading active writers 2026/04/19 03:56:44 DEBUG : file2: reading active writers 2026/04/19 03:56:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 160ms 2026/04/19 03:56:44 DEBUG : Looking for writers 2026/04/19 03:56:44 DEBUG : file1: reading active writers 2026/04/19 03:56:44 DEBUG : file2: reading active writers 2026/04/19 03:56:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 320ms 2026/04/19 03:56:44 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/19 03:56:44 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/19 03:56: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/19 03:56:44 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/19 03:56:44 DEBUG : Looking for writers 2026/04/19 03:56:44 DEBUG : file2: reading active writers 2026/04/19 03:56:44 DEBUG : file1: reading active writers 2026/04/19 03:56:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 640ms 2026/04/19 03:56:44 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:56:44 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56:45 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/19 03:56:45 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/19 03:56: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/19 03:56:45 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/19 03:56:45 DEBUG : Looking for writers 2026/04/19 03:56:45 DEBUG : file1: reading active writers 2026/04/19 03:56:45 DEBUG : file2: reading active writers 2026/04/19 03:56:45 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:45 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:56:45 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56:45 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/19 03:56:45 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/19 03:56: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/19 03:56:45 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/19 03:56:46 DEBUG : Looking for writers 2026/04/19 03:56:46 DEBUG : file1: reading active writers 2026/04/19 03:56:46 DEBUG : file2: reading active writers 2026/04/19 03:56:46 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:46 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:56:46 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56:47 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/19 03:56:47 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/19 03:56:47 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/19 03:56:47 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/19 03:56:47 DEBUG : Looking for writers 2026/04/19 03:56:47 DEBUG : file1: reading active writers 2026/04/19 03:56:47 DEBUG : file2: reading active writers 2026/04/19 03:56:47 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:48 DEBUG : Looking for writers 2026/04/19 03:56:48 DEBUG : file1: reading active writers 2026/04/19 03:56:48 DEBUG : file2: reading active writers 2026/04/19 03:56:48 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:48 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:56:48 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56:49 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/19 03:56:49 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/19 03:56: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/19 03:56:49 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/19 03:56:49 DEBUG : Looking for writers 2026/04/19 03:56:49 DEBUG : file2: reading active writers 2026/04/19 03:56:49 DEBUG : file1: reading active writers 2026/04/19 03:56:49 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:50 DEBUG : Looking for writers 2026/04/19 03:56:50 DEBUG : file1: reading active writers 2026/04/19 03:56:50 DEBUG : file2: reading active writers 2026/04/19 03:56:50 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:51 DEBUG : Looking for writers 2026/04/19 03:56:51 DEBUG : file1: reading active writers 2026/04/19 03:56:51 DEBUG : file2: reading active writers 2026/04/19 03:56:51 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:52 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:56:52 DEBUG : Looking for writers 2026/04/19 03:56:52 DEBUG : file1: reading active writers 2026/04/19 03:56:52 DEBUG : file2: reading active writers 2026/04/19 03:56:52 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:52 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56:52 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/19 03:56:52 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/19 03:56: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/19 03:56:52 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/19 03:56:53 DEBUG : Looking for writers 2026/04/19 03:56:53 DEBUG : file1: reading active writers 2026/04/19 03:56:53 DEBUG : file2: reading active writers 2026/04/19 03:56:53 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:54 DEBUG : Looking for writers 2026/04/19 03:56:54 DEBUG : file1: reading active writers 2026/04/19 03:56:54 DEBUG : file2: reading active writers 2026/04/19 03:56:54 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:55 DEBUG : Looking for writers 2026/04/19 03:56:55 DEBUG : file1: reading active writers 2026/04/19 03:56:55 DEBUG : file2: reading active writers 2026/04/19 03:56:55 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:56 DEBUG : Looking for writers 2026/04/19 03:56:56 DEBUG : file1: reading active writers 2026/04/19 03:56:56 DEBUG : file2: reading active writers 2026/04/19 03:56:56 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:57 DEBUG : Looking for writers 2026/04/19 03:56:57 DEBUG : file2: reading active writers 2026/04/19 03:56:57 DEBUG : file1: reading active writers 2026/04/19 03:56:57 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:58 DEBUG : Looking for writers 2026/04/19 03:56:58 DEBUG : file1: reading active writers 2026/04/19 03:56:58 DEBUG : file2: reading active writers 2026/04/19 03:56:58 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56:59 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:56:59 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:56:59 DEBUG : Looking for writers 2026/04/19 03:56:59 DEBUG : file2: reading active writers 2026/04/19 03:56:59 DEBUG : file1: reading active writers 2026/04/19 03:56:59 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:56: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/19 03:56:59 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/19 03:56: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/19 03:56:59 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/19 03:57:00 DEBUG : Looking for writers 2026/04/19 03:57:00 DEBUG : file1: reading active writers 2026/04/19 03:57:00 DEBUG : file2: reading active writers 2026/04/19 03:57:00 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:01 DEBUG : Looking for writers 2026/04/19 03:57:01 DEBUG : file1: reading active writers 2026/04/19 03:57:01 DEBUG : file2: reading active writers 2026/04/19 03:57:01 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:02 DEBUG : Looking for writers 2026/04/19 03:57:02 DEBUG : file1: reading active writers 2026/04/19 03:57:02 DEBUG : file2: reading active writers 2026/04/19 03:57:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:03 DEBUG : Looking for writers 2026/04/19 03:57:03 DEBUG : file1: reading active writers 2026/04/19 03:57:03 DEBUG : file2: reading active writers 2026/04/19 03:57:03 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:04 DEBUG : Looking for writers 2026/04/19 03:57:04 DEBUG : file1: reading active writers 2026/04/19 03:57:04 DEBUG : file2: reading active writers 2026/04/19 03:57:04 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:05 DEBUG : Looking for writers 2026/04/19 03:57:05 DEBUG : file1: reading active writers 2026/04/19 03:57:05 DEBUG : file2: reading active writers 2026/04/19 03:57:05 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:06 DEBUG : Looking for writers 2026/04/19 03:57:06 DEBUG : file1: reading active writers 2026/04/19 03:57:06 DEBUG : file2: reading active writers 2026/04/19 03:57:06 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:07 DEBUG : Looking for writers 2026/04/19 03:57:07 DEBUG : file1: reading active writers 2026/04/19 03:57:07 DEBUG : file2: reading active writers 2026/04/19 03:57:07 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:08 DEBUG : Looking for writers 2026/04/19 03:57:08 DEBUG : file2: reading active writers 2026/04/19 03:57:08 DEBUG : file1: reading active writers 2026/04/19 03:57:08 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:09 DEBUG : Looking for writers 2026/04/19 03:57:09 DEBUG : file1: reading active writers 2026/04/19 03:57:09 DEBUG : file2: reading active writers 2026/04/19 03:57:09 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:10 DEBUG : Looking for writers 2026/04/19 03:57:10 DEBUG : file1: reading active writers 2026/04/19 03:57:10 DEBUG : file2: reading active writers 2026/04/19 03:57:10 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:11 DEBUG : Looking for writers 2026/04/19 03:57:11 DEBUG : file1: reading active writers 2026/04/19 03:57:11 DEBUG : file2: reading active writers 2026/04/19 03:57:11 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:12 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:57:12 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:57:12 DEBUG : Looking for writers 2026/04/19 03:57:12 DEBUG : file1: reading active writers 2026/04/19 03:57:12 DEBUG : file2: reading active writers 2026/04/19 03:57:12 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:12 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/19 03:57:12 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/19 03:57:12 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/19 03:57:12 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/19 03:57:13 DEBUG : Looking for writers 2026/04/19 03:57:13 DEBUG : file1: reading active writers 2026/04/19 03:57:13 DEBUG : file2: reading active writers 2026/04/19 03:57:13 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:14 ERROR : Exiting even though 0 writers active and 2 cache items in use after 30s Cache{ "file1": &{c:0x23e69fbfe400 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69f5c8008 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473423089728} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14011035436769757935 ext:273082809915 loc:0x46a1880} ATime:{wall:14011035436769956249 ext:273083008220 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, "file2": &{c:0x23e69fbfe400 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69f5c8248 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473423090304} name:file2 opens:0 downloaders: o: fd: info:{ModTime:{wall:14011035436770711777 ext:273083763757 loc:0x46a1880} ATime:{wall:14011035436770888602 ext:273083940582 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:2 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/19 03:57:14 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/19 03:57:22 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:57:22 DEBUG : Looking for writers 2026/04/19 03:57:22 DEBUG : file1: reading active writers 2026/04/19 03:57:22 DEBUG : file2: reading active writers 2026/04/19 03:57:22 DEBUG : Still 0 writers active and 2 cache items in use, waiting 10ms 2026/04/19 03:57:22 DEBUG : Looking for writers 2026/04/19 03:57:22 DEBUG : file1: reading active writers 2026/04/19 03:57:22 DEBUG : file2: reading active writers 2026/04/19 03:57:22 DEBUG : Still 0 writers active and 2 cache items in use, waiting 20ms 2026/04/19 03:57:22 DEBUG : Looking for writers 2026/04/19 03:57:22 DEBUG : file1: reading active writers 2026/04/19 03:57:22 DEBUG : file2: reading active writers 2026/04/19 03:57:22 DEBUG : Still 0 writers active and 2 cache items in use, waiting 40ms 2026/04/19 03:57:22 DEBUG : Looking for writers 2026/04/19 03:57:22 DEBUG : file2: reading active writers 2026/04/19 03:57:22 DEBUG : file1: reading active writers 2026/04/19 03:57:22 DEBUG : Still 0 writers active and 2 cache items in use, waiting 80ms 2026/04/19 03:57:22 DEBUG : Looking for writers 2026/04/19 03:57:22 DEBUG : file1: reading active writers 2026/04/19 03:57:22 DEBUG : file2: reading active writers 2026/04/19 03:57:22 DEBUG : Still 0 writers active and 2 cache items in use, waiting 160ms 2026/04/19 03:57:22 DEBUG : Looking for writers 2026/04/19 03:57:22 DEBUG : file2: reading active writers 2026/04/19 03:57:22 DEBUG : file1: reading active writers 2026/04/19 03:57:22 DEBUG : Still 0 writers active and 2 cache items in use, waiting 320ms 2026/04/19 03:57:22 DEBUG : Looking for writers 2026/04/19 03:57:22 DEBUG : file1: reading active writers 2026/04/19 03:57:22 DEBUG : file2: reading active writers 2026/04/19 03:57:22 DEBUG : Still 0 writers active and 2 cache items in use, waiting 640ms 2026/04/19 03:57:23 DEBUG : Looking for writers 2026/04/19 03:57:23 DEBUG : file2: reading active writers 2026/04/19 03:57:23 DEBUG : file1: reading active writers 2026/04/19 03:57:23 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:24 DEBUG : Looking for writers 2026/04/19 03:57:24 DEBUG : file1: reading active writers 2026/04/19 03:57:24 DEBUG : file2: reading active writers 2026/04/19 03:57:24 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:25 DEBUG : Looking for writers 2026/04/19 03:57:25 DEBUG : file2: reading active writers 2026/04/19 03:57:25 DEBUG : file1: reading active writers 2026/04/19 03:57:25 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:26 DEBUG : Looking for writers 2026/04/19 03:57:26 DEBUG : file1: reading active writers 2026/04/19 03:57:26 DEBUG : file2: reading active writers 2026/04/19 03:57:26 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:27 DEBUG : Looking for writers 2026/04/19 03:57:27 DEBUG : file1: reading active writers 2026/04/19 03:57:27 DEBUG : file2: reading active writers 2026/04/19 03:57:27 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:28 DEBUG : Looking for writers 2026/04/19 03:57:28 DEBUG : file1: reading active writers 2026/04/19 03:57:28 DEBUG : file2: reading active writers 2026/04/19 03:57:28 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:29 DEBUG : Looking for writers 2026/04/19 03:57:29 DEBUG : file1: reading active writers 2026/04/19 03:57:29 DEBUG : file2: reading active writers 2026/04/19 03:57:29 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:30 DEBUG : Looking for writers 2026/04/19 03:57:30 DEBUG : file1: reading active writers 2026/04/19 03:57:30 DEBUG : file2: reading active writers 2026/04/19 03:57:30 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:31 DEBUG : Looking for writers 2026/04/19 03:57:31 DEBUG : file1: reading active writers 2026/04/19 03:57:31 DEBUG : file2: reading active writers 2026/04/19 03:57:31 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:32 DEBUG : Looking for writers 2026/04/19 03:57:32 DEBUG : file1: reading active writers 2026/04/19 03:57:32 DEBUG : file2: reading active writers 2026/04/19 03:57:32 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:33 DEBUG : Looking for writers 2026/04/19 03:57:33 DEBUG : file1: reading active writers 2026/04/19 03:57:33 DEBUG : file2: reading active writers 2026/04/19 03:57:33 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:34 DEBUG : Looking for writers 2026/04/19 03:57:34 DEBUG : file1: reading active writers 2026/04/19 03:57:34 DEBUG : file2: reading active writers 2026/04/19 03:57:34 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:35 DEBUG : Looking for writers 2026/04/19 03:57:35 DEBUG : file1: reading active writers 2026/04/19 03:57:35 DEBUG : file2: reading active writers 2026/04/19 03:57:35 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:36 DEBUG : Looking for writers 2026/04/19 03:57:36 DEBUG : file1: reading active writers 2026/04/19 03:57:36 DEBUG : file2: reading active writers 2026/04/19 03:57:36 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:37 DEBUG : Looking for writers 2026/04/19 03:57:37 DEBUG : file1: reading active writers 2026/04/19 03:57:37 DEBUG : file2: reading active writers 2026/04/19 03:57:37 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:38 DEBUG : file2: vfs cache: starting upload 2026/04/19 03:57:38 DEBUG : file1: vfs cache: starting upload 2026/04/19 03:57:38 DEBUG : Looking for writers 2026/04/19 03:57:38 DEBUG : file1: reading active writers 2026/04/19 03:57:38 DEBUG : file2: reading active writers 2026/04/19 03:57:38 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:39 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/19 03:57:39 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/19 03:57:39 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/19 03:57:39 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/19 03:57:39 DEBUG : Looking for writers 2026/04/19 03:57:39 DEBUG : file2: reading active writers 2026/04/19 03:57:39 DEBUG : file1: reading active writers 2026/04/19 03:57:39 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:40 DEBUG : Looking for writers 2026/04/19 03:57:40 DEBUG : file1: reading active writers 2026/04/19 03:57:40 DEBUG : file2: reading active writers 2026/04/19 03:57:40 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:41 DEBUG : Looking for writers 2026/04/19 03:57:41 DEBUG : file1: reading active writers 2026/04/19 03:57:41 DEBUG : file2: reading active writers 2026/04/19 03:57:41 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:42 DEBUG : Looking for writers 2026/04/19 03:57:42 DEBUG : file1: reading active writers 2026/04/19 03:57:42 DEBUG : file2: reading active writers 2026/04/19 03:57:42 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:43 DEBUG : Looking for writers 2026/04/19 03:57:43 DEBUG : file1: reading active writers 2026/04/19 03:57:43 DEBUG : file2: reading active writers 2026/04/19 03:57:43 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2026/04/19 03:57:43 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file2 not removed, freed 0 bytes 2026/04/19 03:57:43 INFO : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaned: objects 2 (was 2) in use 2, to upload 2, uploading 0, total size 0 (was 0) 2026/04/19 03:57:44 DEBUG : Looking for writers 2026/04/19 03:57:44 DEBUG : file1: reading active writers 2026/04/19 03:57:44 DEBUG : file2: reading active writers 2026/04/19 03:57:44 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:45 DEBUG : Looking for writers 2026/04/19 03:57:45 DEBUG : file2: reading active writers 2026/04/19 03:57:45 DEBUG : file1: reading active writers 2026/04/19 03:57:45 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:46 DEBUG : Looking for writers 2026/04/19 03:57:46 DEBUG : file1: reading active writers 2026/04/19 03:57:46 DEBUG : file2: reading active writers 2026/04/19 03:57:46 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:47 DEBUG : Looking for writers 2026/04/19 03:57:47 DEBUG : file1: reading active writers 2026/04/19 03:57:47 DEBUG : file2: reading active writers 2026/04/19 03:57:47 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:48 DEBUG : Looking for writers 2026/04/19 03:57:48 DEBUG : file1: reading active writers 2026/04/19 03:57:48 DEBUG : file2: reading active writers 2026/04/19 03:57:48 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:49 DEBUG : Looking for writers 2026/04/19 03:57:49 DEBUG : file1: reading active writers 2026/04/19 03:57:49 DEBUG : file2: reading active writers 2026/04/19 03:57:49 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:50 DEBUG : Looking for writers 2026/04/19 03:57:50 DEBUG : file1: reading active writers 2026/04/19 03:57:50 DEBUG : file2: reading active writers 2026/04/19 03:57:50 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:51 DEBUG : Looking for writers 2026/04/19 03:57:51 DEBUG : file1: reading active writers 2026/04/19 03:57:51 DEBUG : file2: reading active writers 2026/04/19 03:57:51 DEBUG : Still 0 writers active and 2 cache items in use, waiting 1s 2026/04/19 03:57:52 ERROR : Exiting even though 0 writers active and 2 cache items in use after 30s Cache{ "file1": &{c:0x23e69fbfe400 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69f5c8008 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473423089728} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14011035436769757935 ext:273082809915 loc:0x46a1880} ATime:{wall:14011035436769956249 ext:273083008220 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, "file2": &{c:0x23e69fbfe400 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69f5c8248 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473423090304} name:file2 opens:0 downloaders: o: fd: info:{ModTime:{wall:14011035436770711777 ext:273083763757 loc:0x46a1880} ATime:{wall:14011035436770888602 ext:273083940582 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:2 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/04/19 03:57:52 DEBUG : >WaitForWriters: 2026/04/19 03:57:52 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteNoWrite (68.90s) === RUN TestRWFileHandleSizeTruncateExisting run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:57:52 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:57:52 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:57:52 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:52 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:52 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:52 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:57:52 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:52 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:52 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:57:52 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:52 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:57:53 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:57:53 DEBUG : Looking for writers 2026/04/19 03:57:53 DEBUG : >WaitForWriters: 2026/04/19 03:57:53 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeTruncateExisting (2.52s) === RUN TestRWFileHandleSizeCreateExisting run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:57:55 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:57:55 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:57:55 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:55 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:55 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:55 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:57:55 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:55 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:55 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:57:55 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:55 INFO : drime root 'rclone-test-gufunuk4pemu': 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-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:57:56 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:57:56 DEBUG : Looking for writers 2026/04/19 03:57:56 DEBUG : >WaitForWriters: 2026/04/19 03:57:56 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeCreateExisting (2.68s) === RUN TestRWFileModTimeWithOpenWriters run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:57:57 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:57:57 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/19 03:57:57 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:57 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:57 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:57 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:57:57 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:57 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:57 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/19 03:57:57 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-gufunuk4pemu" 2026/04/19 03:57:57 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:57:57 INFO : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/19 03:57:58 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:57:58 DEBUG : file1: newRWFileHandle: 2026/04/19 03:57:58 DEBUG : file1(0x23e69fa8d500): openPending: 2026/04/19 03:57:58 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/19 03:57:58 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:57:58 DEBUG : file1(0x23e69fa8d500): >openPending: err= 2026/04/19 03:57:58 DEBUG : file1: >newRWFileHandle: err= 2026/04/19 03:57:58 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:57:58 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/19 03:57:58 DEBUG : file1: >OpenFile: fd=file1 (rw), err= run.go:290: Failed to put "time_test" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:57:58 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:57:58 DEBUG : Looking for writers 2026/04/19 03:57:58 DEBUG : file1: reading active writers 2026/04/19 03:57:58 DEBUG : file1: active writers 1 2026/04/19 03:57:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 10ms 2026/04/19 03:57:58 DEBUG : Looking for writers 2026/04/19 03:57:58 DEBUG : file1: reading active writers 2026/04/19 03:57:58 DEBUG : file1: active writers 1 2026/04/19 03:57:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 20ms 2026/04/19 03:57:58 DEBUG : Looking for writers 2026/04/19 03:57:58 DEBUG : file1: reading active writers 2026/04/19 03:57:58 DEBUG : file1: active writers 1 2026/04/19 03:57:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 40ms 2026/04/19 03:57:58 DEBUG : Looking for writers 2026/04/19 03:57:58 DEBUG : file1: reading active writers 2026/04/19 03:57:58 DEBUG : file1: active writers 1 2026/04/19 03:57:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 80ms 2026/04/19 03:57:58 DEBUG : Looking for writers 2026/04/19 03:57:58 DEBUG : file1: reading active writers 2026/04/19 03:57:58 DEBUG : file1: active writers 1 2026/04/19 03:57:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 160ms 2026/04/19 03:57:58 DEBUG : Looking for writers 2026/04/19 03:57:58 DEBUG : file1: reading active writers 2026/04/19 03:57:58 DEBUG : file1: active writers 1 2026/04/19 03:57:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 320ms 2026/04/19 03:57:59 DEBUG : Looking for writers 2026/04/19 03:57:59 DEBUG : file1: reading active writers 2026/04/19 03:57:59 DEBUG : file1: active writers 1 2026/04/19 03:57:59 DEBUG : Still 1 writers active and 1 cache items in use, waiting 640ms 2026/04/19 03:57:59 DEBUG : Looking for writers 2026/04/19 03:57:59 DEBUG : file1: reading active writers 2026/04/19 03:57:59 DEBUG : file1: active writers 1 2026/04/19 03:57:59 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:00 DEBUG : Looking for writers 2026/04/19 03:58:00 DEBUG : file1: reading active writers 2026/04/19 03:58:00 DEBUG : file1: active writers 1 2026/04/19 03:58:00 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:01 DEBUG : Looking for writers 2026/04/19 03:58:01 DEBUG : file1: reading active writers 2026/04/19 03:58:01 DEBUG : file1: active writers 1 2026/04/19 03:58:01 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:02 DEBUG : Looking for writers 2026/04/19 03:58:02 DEBUG : file1: reading active writers 2026/04/19 03:58:02 DEBUG : file1: active writers 1 2026/04/19 03:58:02 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:03 DEBUG : Looking for writers 2026/04/19 03:58:03 DEBUG : file1: reading active writers 2026/04/19 03:58:03 DEBUG : file1: active writers 1 2026/04/19 03:58:03 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:04 DEBUG : Looking for writers 2026/04/19 03:58:04 DEBUG : file1: reading active writers 2026/04/19 03:58:04 DEBUG : file1: active writers 1 2026/04/19 03:58:04 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:05 DEBUG : Looking for writers 2026/04/19 03:58:05 DEBUG : file1: reading active writers 2026/04/19 03:58:05 DEBUG : file1: active writers 1 2026/04/19 03:58:05 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:06 DEBUG : Looking for writers 2026/04/19 03:58:06 DEBUG : file1: reading active writers 2026/04/19 03:58:06 DEBUG : file1: active writers 1 2026/04/19 03:58:06 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:07 DEBUG : Looking for writers 2026/04/19 03:58:07 DEBUG : file1: reading active writers 2026/04/19 03:58:07 DEBUG : file1: active writers 1 2026/04/19 03:58:07 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:08 DEBUG : Looking for writers 2026/04/19 03:58:08 DEBUG : file1: reading active writers 2026/04/19 03:58:08 DEBUG : file1: active writers 1 2026/04/19 03:58:08 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:09 DEBUG : Looking for writers 2026/04/19 03:58:09 DEBUG : file1: reading active writers 2026/04/19 03:58:09 DEBUG : file1: active writers 1 2026/04/19 03:58:09 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:10 DEBUG : Looking for writers 2026/04/19 03:58:10 DEBUG : file1: reading active writers 2026/04/19 03:58:10 DEBUG : file1: active writers 1 2026/04/19 03:58:10 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:11 DEBUG : Looking for writers 2026/04/19 03:58:11 DEBUG : file1: reading active writers 2026/04/19 03:58:11 DEBUG : file1: active writers 1 2026/04/19 03:58:11 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:12 DEBUG : Looking for writers 2026/04/19 03:58:12 DEBUG : file1: reading active writers 2026/04/19 03:58:12 DEBUG : file1: active writers 1 2026/04/19 03:58:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:13 DEBUG : Looking for writers 2026/04/19 03:58:13 DEBUG : file1: reading active writers 2026/04/19 03:58:13 DEBUG : file1: active writers 1 2026/04/19 03:58:13 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:14 DEBUG : Looking for writers 2026/04/19 03:58:14 DEBUG : file1: reading active writers 2026/04/19 03:58:14 DEBUG : file1: active writers 1 2026/04/19 03:58:14 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:15 DEBUG : Looking for writers 2026/04/19 03:58:15 DEBUG : file1: reading active writers 2026/04/19 03:58:15 DEBUG : file1: active writers 1 2026/04/19 03:58:15 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:16 DEBUG : Looking for writers 2026/04/19 03:58:16 DEBUG : file1: reading active writers 2026/04/19 03:58:16 DEBUG : file1: active writers 1 2026/04/19 03:58:16 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:17 DEBUG : Looking for writers 2026/04/19 03:58:17 DEBUG : file1: reading active writers 2026/04/19 03:58:17 DEBUG : file1: active writers 1 2026/04/19 03:58:17 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:18 DEBUG : Looking for writers 2026/04/19 03:58:18 DEBUG : file1: reading active writers 2026/04/19 03:58:18 DEBUG : file1: active writers 1 2026/04/19 03:58:18 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:19 DEBUG : Looking for writers 2026/04/19 03:58:19 DEBUG : file1: reading active writers 2026/04/19 03:58:19 DEBUG : file1: active writers 1 2026/04/19 03:58:19 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:20 DEBUG : Looking for writers 2026/04/19 03:58:20 DEBUG : file1: reading active writers 2026/04/19 03:58:20 DEBUG : file1: active writers 1 2026/04/19 03:58:20 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:21 DEBUG : Looking for writers 2026/04/19 03:58:21 DEBUG : file1: reading active writers 2026/04/19 03:58:21 DEBUG : file1: active writers 1 2026/04/19 03:58:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:22 DEBUG : Looking for writers 2026/04/19 03:58:22 DEBUG : file1: reading active writers 2026/04/19 03:58:22 DEBUG : file1: active writers 1 2026/04/19 03:58:22 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:23 DEBUG : Looking for writers 2026/04/19 03:58:23 DEBUG : file1: reading active writers 2026/04/19 03:58:23 DEBUG : file1: active writers 1 2026/04/19 03:58:23 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:24 DEBUG : Looking for writers 2026/04/19 03:58:24 DEBUG : file1: reading active writers 2026/04/19 03:58:24 DEBUG : file1: active writers 1 2026/04/19 03:58:24 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:25 DEBUG : Looking for writers 2026/04/19 03:58:25 DEBUG : file1: reading active writers 2026/04/19 03:58:25 DEBUG : file1: active writers 1 2026/04/19 03:58:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:26 DEBUG : Looking for writers 2026/04/19 03:58:26 DEBUG : file1: reading active writers 2026/04/19 03:58:26 DEBUG : file1: active writers 1 2026/04/19 03:58:26 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:27 DEBUG : Looking for writers 2026/04/19 03:58:27 DEBUG : file1: reading active writers 2026/04/19 03:58:27 DEBUG : file1: active writers 1 2026/04/19 03:58:27 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/04/19 03:58:28 ERROR : Exiting even though 1 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x23e6a0038700 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x23e69fc35208 notify:{wait:0 notify:0 lock:0 head: tail:} checker:39473429828160} name:file1 opens:1 downloaders: o: fd:0x23e69f5026a8 info:{ModTime:{wall:14011035516335067834 ext:347191224858 loc:0x46a1880} ATime:{wall:14011035516335067834 ext:347191224858 loc:0x46a1880} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:0 pendingAccesses:0 modified:true beingReset:false graceTimer:}, } 2026/04/19 03:58:28 DEBUG : >WaitForWriters: 2026/04/19 03:58:28 DEBUG : drime root 'rclone-test-gufunuk4pemu': vfs cache: cleaner exiting --- FAIL: TestRWFileModTimeWithOpenWriters (30.95s) === RUN TestCaseSensitivity run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" run.go:290: Failed to put "FiLeA" to "drime root 'rclone-test-gufunuk4pemu'": 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.74s) === RUN TestUnicodeNormalization run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" run.go:290: Failed to put "normal name with no special characters.txt" to "drime root 'rclone-test-gufunuk4pemu'": 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.74s) === RUN TestVFSStat run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:58:30 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:58:30 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:58:30 DEBUG : Looking for writers 2026/04/19 03:58:30 DEBUG : >WaitForWriters: --- FAIL: TestVFSStat (0.78s) === RUN TestVFSStatParent run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:58:31 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:58:31 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:58:31 DEBUG : Looking for writers 2026/04/19 03:58:31 DEBUG : >WaitForWriters: --- FAIL: TestVFSStatParent (0.73s) === RUN TestVFSOpenFile run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:58:31 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "file1" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:58:32 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:58:32 DEBUG : Looking for writers 2026/04/19 03:58:32 DEBUG : >WaitForWriters: --- FAIL: TestVFSOpenFile (0.77s) === RUN TestVFSRename run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:58:32 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir/file2" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:58:33 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:58:33 DEBUG : Looking for writers 2026/04/19 03:58:33 DEBUG : >WaitForWriters: --- FAIL: TestVFSRename (2.49s) === RUN TestWriteFileHandleMethods run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:58:35 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:58:35 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:58:35 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:58:35 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:58:35 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:58:35 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:58:35 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:58:35 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2026/04/19 03:58:35 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2026/04/19 03:58:35 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2026/04/19 03:58:35 DEBUG : drime root 'rclone-test-gufunuk4pemu': File to upload is small (5 bytes), uploading instead of streaming 2026/04/19 03:58:35 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/19 03:58:35 DEBUG : file1: Remove: 2026/04/19 03:58:35 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/19 03:58:35 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/19 03:58:44 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:58:44 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:58:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:58:44 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:58:44 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:58:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:58:44 DEBUG : drime root 'rclone-test-gufunuk4pemu': File to upload is small (0 bytes), uploading instead of streaming 2026/04/19 03:58: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/19 03:58:44 DEBUG : file1: Remove: 2026/04/19 03:58:44 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/19 03:58:44 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/19 03:58:44 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:58:44 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:58:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:58:44 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:58:44 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:58:44 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/19 03:58:44 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:58:44 DEBUG : Looking for writers 2026/04/19 03:58:44 DEBUG : file1: reading active writers 2026/04/19 03:58:44 DEBUG : file1: active writers 1 2026/04/19 03:58:44 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms 2026/04/19 03:58:44 DEBUG : Looking for writers 2026/04/19 03:58:44 DEBUG : file1: reading active writers 2026/04/19 03:58:44 DEBUG : file1: active writers 1 2026/04/19 03:58:44 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms 2026/04/19 03:58:44 DEBUG : Looking for writers 2026/04/19 03:58:44 DEBUG : file1: reading active writers 2026/04/19 03:58:44 DEBUG : file1: active writers 1 2026/04/19 03:58:44 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms 2026/04/19 03:58:45 DEBUG : Looking for writers 2026/04/19 03:58:45 DEBUG : file1: reading active writers 2026/04/19 03:58:45 DEBUG : file1: active writers 1 2026/04/19 03:58:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms 2026/04/19 03:58:45 DEBUG : Looking for writers 2026/04/19 03:58:45 DEBUG : file1: reading active writers 2026/04/19 03:58:45 DEBUG : file1: active writers 1 2026/04/19 03:58:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms 2026/04/19 03:58:45 DEBUG : Looking for writers 2026/04/19 03:58:45 DEBUG : file1: reading active writers 2026/04/19 03:58:45 DEBUG : file1: active writers 1 2026/04/19 03:58:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms 2026/04/19 03:58:45 DEBUG : Looking for writers 2026/04/19 03:58:45 DEBUG : file1: reading active writers 2026/04/19 03:58:45 DEBUG : file1: active writers 1 2026/04/19 03:58:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms 2026/04/19 03:58:46 DEBUG : Looking for writers 2026/04/19 03:58:46 DEBUG : file1: reading active writers 2026/04/19 03:58:46 DEBUG : file1: active writers 1 2026/04/19 03:58:46 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:47 DEBUG : Looking for writers 2026/04/19 03:58:47 DEBUG : file1: reading active writers 2026/04/19 03:58:47 DEBUG : file1: active writers 1 2026/04/19 03:58:47 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:48 DEBUG : Looking for writers 2026/04/19 03:58:48 DEBUG : file1: reading active writers 2026/04/19 03:58:48 DEBUG : file1: active writers 1 2026/04/19 03:58:48 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:49 DEBUG : Looking for writers 2026/04/19 03:58:49 DEBUG : file1: reading active writers 2026/04/19 03:58:49 DEBUG : file1: active writers 1 2026/04/19 03:58:49 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:50 DEBUG : Looking for writers 2026/04/19 03:58:50 DEBUG : file1: reading active writers 2026/04/19 03:58:50 DEBUG : file1: active writers 1 2026/04/19 03:58:50 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:51 DEBUG : Looking for writers 2026/04/19 03:58:51 DEBUG : file1: reading active writers 2026/04/19 03:58:51 DEBUG : file1: active writers 1 2026/04/19 03:58:51 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:52 DEBUG : Looking for writers 2026/04/19 03:58:52 DEBUG : file1: reading active writers 2026/04/19 03:58:52 DEBUG : file1: active writers 1 2026/04/19 03:58:52 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:53 DEBUG : Looking for writers 2026/04/19 03:58:53 DEBUG : file1: reading active writers 2026/04/19 03:58:53 DEBUG : file1: active writers 1 2026/04/19 03:58:53 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:54 DEBUG : Looking for writers 2026/04/19 03:58:54 DEBUG : file1: reading active writers 2026/04/19 03:58:54 DEBUG : file1: active writers 1 2026/04/19 03:58:54 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:55 DEBUG : Looking for writers 2026/04/19 03:58:55 DEBUG : file1: reading active writers 2026/04/19 03:58:55 DEBUG : file1: active writers 1 2026/04/19 03:58:55 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:56 DEBUG : Looking for writers 2026/04/19 03:58:56 DEBUG : file1: reading active writers 2026/04/19 03:58:56 DEBUG : file1: active writers 1 2026/04/19 03:58:56 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:57 DEBUG : Looking for writers 2026/04/19 03:58:57 DEBUG : file1: reading active writers 2026/04/19 03:58:57 DEBUG : file1: active writers 1 2026/04/19 03:58:57 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:58 DEBUG : Looking for writers 2026/04/19 03:58:58 DEBUG : file1: reading active writers 2026/04/19 03:58:58 DEBUG : file1: active writers 1 2026/04/19 03:58:58 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:58:59 DEBUG : Looking for writers 2026/04/19 03:58:59 DEBUG : file1: reading active writers 2026/04/19 03:58:59 DEBUG : file1: active writers 1 2026/04/19 03:58:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:00 DEBUG : Looking for writers 2026/04/19 03:59:00 DEBUG : file1: reading active writers 2026/04/19 03:59:00 DEBUG : file1: active writers 1 2026/04/19 03:59:00 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:01 DEBUG : Looking for writers 2026/04/19 03:59:01 DEBUG : file1: reading active writers 2026/04/19 03:59:01 DEBUG : file1: active writers 1 2026/04/19 03:59:01 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:02 DEBUG : Looking for writers 2026/04/19 03:59:02 DEBUG : file1: reading active writers 2026/04/19 03:59:02 DEBUG : file1: active writers 1 2026/04/19 03:59:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:03 DEBUG : Looking for writers 2026/04/19 03:59:03 DEBUG : file1: reading active writers 2026/04/19 03:59:03 DEBUG : file1: active writers 1 2026/04/19 03:59:03 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:04 DEBUG : Looking for writers 2026/04/19 03:59:04 DEBUG : file1: reading active writers 2026/04/19 03:59:04 DEBUG : file1: active writers 1 2026/04/19 03:59:04 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:05 DEBUG : Looking for writers 2026/04/19 03:59:05 DEBUG : file1: reading active writers 2026/04/19 03:59:05 DEBUG : file1: active writers 1 2026/04/19 03:59:05 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:06 DEBUG : Looking for writers 2026/04/19 03:59:06 DEBUG : file1: reading active writers 2026/04/19 03:59:06 DEBUG : file1: active writers 1 2026/04/19 03:59:06 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:07 DEBUG : Looking for writers 2026/04/19 03:59:07 DEBUG : file1: reading active writers 2026/04/19 03:59:07 DEBUG : file1: active writers 1 2026/04/19 03:59:07 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:08 DEBUG : Looking for writers 2026/04/19 03:59:08 DEBUG : file1: reading active writers 2026/04/19 03:59:08 DEBUG : file1: active writers 1 2026/04/19 03:59:08 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:09 DEBUG : Looking for writers 2026/04/19 03:59:09 DEBUG : file1: reading active writers 2026/04/19 03:59:09 DEBUG : file1: active writers 1 2026/04/19 03:59:09 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:10 DEBUG : Looking for writers 2026/04/19 03:59:10 DEBUG : file1: reading active writers 2026/04/19 03:59:10 DEBUG : file1: active writers 1 2026/04/19 03:59:10 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:11 DEBUG : Looking for writers 2026/04/19 03:59:11 DEBUG : file1: reading active writers 2026/04/19 03:59:11 DEBUG : file1: active writers 1 2026/04/19 03:59:11 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:12 DEBUG : Looking for writers 2026/04/19 03:59:12 DEBUG : file1: reading active writers 2026/04/19 03:59:12 DEBUG : file1: active writers 1 2026/04/19 03:59:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:13 DEBUG : Looking for writers 2026/04/19 03:59:13 DEBUG : file1: reading active writers 2026/04/19 03:59:13 DEBUG : file1: active writers 1 2026/04/19 03:59:13 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:14 DEBUG : Looking for writers 2026/04/19 03:59:14 DEBUG : file1: reading active writers 2026/04/19 03:59:14 DEBUG : file1: active writers 1 2026/04/19 03:59:14 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/04/19 03:59:14 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s Cache: 2026/04/19 03:59:14 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleMethods (40.42s) === RUN TestWriteFileHandleWriteAt run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:15 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:59:15 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:59:15 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:59:15 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:15 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:59:15 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:59:15 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:15 DEBUG : file1: waiting for in-sequence write to 100 for 1s 2026/04/19 03:59:16 DEBUG : file1: aborting in-sequence write wait, off=100 2026/04/19 03:59:16 DEBUG : file1: failed to wait for in-sequence write to 100 2026/04/19 03:59:16 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes 2026/04/19 03:59:16 DEBUG : drime root 'rclone-test-gufunuk4pemu': File to upload is small (11 bytes), uploading instead of streaming 2026/04/19 03:59:17 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/19 03:59:17 DEBUG : file1: Remove: 2026/04/19 03:59:17 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/19 03:59:17 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/19 03:59:17 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/19 03:59:25 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:25 DEBUG : Looking for writers 2026/04/19 03:59:25 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleWriteAt (10.79s) === RUN TestWriteFileHandleFlush run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:26 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:59:26 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:59:26 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:59:26 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:26 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:59:26 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:59:26 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2026/04/19 03:59:26 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:26 DEBUG : drime root 'rclone-test-gufunuk4pemu': File to upload is small (5 bytes), uploading instead of streaming 2026/04/19 03:59:26 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/19 03:59:26 DEBUG : file1: Remove: 2026/04/19 03:59:26 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/19 03:59:26 DEBUG : file1: >Remove: err= 2026/04/19 03:59:26 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/19 03:59:26 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/19 03:59:26 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:26 DEBUG : Looking for writers 2026/04/19 03:59:26 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleFlush (0.93s) === RUN TestWriteFileHandleRelease run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:27 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:59:27 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:59:27 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:59:27 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:27 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:59:27 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:59:27 DEBUG : file1: WriteFileHandle.Release closing 2026/04/19 03:59:27 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:27 DEBUG : drime root 'rclone-test-gufunuk4pemu': File to upload is small (0 bytes), uploading instead of streaming 2026/04/19 03:59:27 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/19 03:59:27 DEBUG : file1: Remove: 2026/04/19 03:59:27 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/19 03:59:27 DEBUG : file1: >Remove: err= 2026/04/19 03:59:27 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/19 03:59:27 DEBUG : file1: WriteFileHandle.Release nothing to do 2026/04/19 03:59:27 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:27 DEBUG : Looking for writers 2026/04/19 03:59:27 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleRelease (0.95s) === RUN TestWriteFileModTimeWithOpenWriters run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:28 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:59:28 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:59:28 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:59:28 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:28 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:59:28 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:59:28 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:28 DEBUG : drime root 'rclone-test-gufunuk4pemu': File to upload is small (2 bytes), uploading instead of streaming 2026/04/19 03:59:28 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/19 03:59:28 DEBUG : file1: Remove: 2026/04/19 03:59:28 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/19 03:59:28 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/19 03:59:28 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:28 DEBUG : Looking for writers 2026/04/19 03:59:28 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileModTimeWithOpenWriters (0.95s) === RUN TestFileReadAtZeroLength run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:29 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:59:29 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:59:29 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:59:29 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:29 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:59:29 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:59:29 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:29 DEBUG : drime root 'rclone-test-gufunuk4pemu': File to upload is small (0 bytes), uploading instead of streaming 2026/04/19 03:59:29 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/19 03:59:29 DEBUG : file1: Remove: 2026/04/19 03:59:29 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/19 03:59:29 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/19 03:59:29 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/04/19 03:59:29 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/19 03:59:29 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:29 DEBUG : Looking for writers 2026/04/19 03:59:29 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtZeroLength (0.95s) === RUN TestFileReadAtNonZeroLength run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:30 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote 2026/04/19 03:59:30 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/19 03:59:30 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/19 03:59:30 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:30 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/19 03:59:30 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/19 03:59:30 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/19 03:59:30 DEBUG : drime root 'rclone-test-gufunuk4pemu': File to upload is small (100 bytes), uploading instead of streaming 2026/04/19 03:59:30 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/19 03:59:30 DEBUG : file1: Remove: 2026/04/19 03:59:30 DEBUG : Added virtual directory entry vDel: "file1" 2026/04/19 03:59:30 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/19 03:59:30 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/04/19 03:59:30 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/19 03:59:30 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:30 DEBUG : Looking for writers 2026/04/19 03:59:30 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtNonZeroLength (1.01s) === RUN TestZipManyFiles run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:31 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "flat/f000.txt" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:59:32 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:32 DEBUG : Looking for writers 2026/04/19 03:59:32 DEBUG : >WaitForWriters: --- FAIL: TestZipManyFiles (2.43s) === RUN TestZipManySubDirs run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:33 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "a/top.txt" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:59:34 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:34 DEBUG : Looking for writers 2026/04/19 03:59:34 DEBUG : >WaitForWriters: --- FAIL: TestZipManySubDirs (2.65s) === RUN TestZipLargeFiles run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:36 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "bigdir/big.bin" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:59:37 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:37 DEBUG : Looking for writers 2026/04/19 03:59:37 DEBUG : >WaitForWriters: --- FAIL: TestZipLargeFiles (2.76s) === RUN TestZipDirsInRoot run.go:185: Remote "drime root 'rclone-test-gufunuk4pemu'", Local "Local file system at /tmp/rclone4081266440", Modify Window "876000h0m0s" 2026/04/19 03:59:38 INFO : drime root 'rclone-test-gufunuk4pemu': poll-interval is not supported by this remote run.go:290: Failed to put "dir1/a.txt" to "drime root 'rclone-test-gufunuk4pemu'": failed to upload file: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan." 2026/04/19 03:59:40 DEBUG : WaitForWriters: timeout=30s 2026/04/19 03:59:40 DEBUG : Looking for writers 2026/04/19 03:59:40 DEBUG : >WaitForWriters: --- FAIL: TestZipDirsInRoot (2.67s) FAIL 2026/04/19 03:59:41 DEBUG : drime root 'rclone-test-gufunuk4pemu': 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 7m31.408794093s (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]