"./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestCaseSensitivity|TestDirCreate|TestDirFileOpen|TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMetadataExtension|TestDirMethods|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleMethodsWrite|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestRWFileHandleWriteAt|TestRWFileModTimeWithOpenWriters|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|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 3/5) 2026/06/09 04:33:45 DEBUG : Creating backend with remote "TestDrime:rclone-test-kuwutil9meqo" 2026/06/09 04:33:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/06/09 04:33:48 DEBUG : Creating backend with remote "/tmp/rclone3330174522" === RUN TestDirHandleMethods run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:33:48 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:33:53 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:33:53 DEBUG : Looking for writers 2026/06/09 04:33:53 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleMethods (11.01s) === RUN TestDirHandleReaddir run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:33:59 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:34:04 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:34:04 DEBUG : Looking for writers 2026/06/09 04:34:04 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddir (11.77s) === RUN TestDirHandleReaddirnames run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:34:10 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:34:15 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:34:15 DEBUG : Looking for writers 2026/06/09 04:34:15 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddirnames (8.97s) === RUN TestDirMethods run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:34:19 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:34:24 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:34:24 DEBUG : Looking for writers 2026/06/09 04:34:24 DEBUG : >WaitForWriters: --- FAIL: TestDirMethods (10.52s) === RUN TestDirForgetAll run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:34:30 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:34:34 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:34:34 DEBUG : Looking for writers 2026/06/09 04:34:34 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetAll (8.58s) === RUN TestDirForgetPath run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:34:38 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:34:42 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:34:42 DEBUG : Looking for writers 2026/06/09 04:34:42 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetPath (9.18s) === RUN TestDirWalk run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:34:48 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:34:53 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:34:53 DEBUG : Looking for writers 2026/06/09 04:34:53 DEBUG : >WaitForWriters: --- FAIL: TestDirWalk (10.49s) === RUN TestDirSetModTime run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:34:58 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:35:02 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:35:02 DEBUG : Looking for writers 2026/06/09 04:35:02 DEBUG : >WaitForWriters: --- FAIL: TestDirSetModTime (8.26s) === RUN TestDirStat run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:35:06 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:35:11 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:35:11 DEBUG : Looking for writers 2026/06/09 04:35:11 DEBUG : >WaitForWriters: --- FAIL: TestDirStat (11.06s) === RUN TestDirReadDirAll run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:35:17 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:35:23 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:35:23 DEBUG : Looking for writers 2026/06/09 04:35:23 DEBUG : >WaitForWriters: --- FAIL: TestDirReadDirAll (9.61s) === RUN TestDirOpen run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:35:27 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:35:30 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:35:30 DEBUG : Looking for writers 2026/06/09 04:35:30 DEBUG : >WaitForWriters: --- FAIL: TestDirOpen (7.06s) === RUN TestDirCreate run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:35:34 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:35:39 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:35:39 DEBUG : Looking for writers 2026/06/09 04:35:39 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (10.19s) === RUN TestDirMkdir run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:35:44 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:35:48 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:35:48 DEBUG : Looking for writers 2026/06/09 04:35:48 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdir (7.90s) === RUN TestDirMkdirSub run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:35:52 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:35:55 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:35:55 DEBUG : Looking for writers 2026/06/09 04:35:55 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdirSub (7.35s) === RUN TestDirRemoveName run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:36:00 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:36:04 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:36:04 DEBUG : Looking for writers 2026/06/09 04:36:04 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (9.73s) === RUN TestDirRename run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:36:09 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:36:13 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:36:13 DEBUG : Looking for writers 2026/06/09 04:36:13 DEBUG : >WaitForWriters: --- FAIL: TestDirRename (8.01s) === RUN TestDirFileOpen run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:36:17 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:36:20 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:36:20 DEBUG : Looking for writers 2026/06/09 04:36:20 DEBUG : >WaitForWriters: --- FAIL: TestDirFileOpen (8.80s) === RUN TestDirMetadataExtension run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:36:26 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:36:31 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:36:31 DEBUG : Looking for writers 2026/06/09 04:36:31 DEBUG : >WaitForWriters: --- FAIL: TestDirMetadataExtension (9.96s) === RUN TestFileMethods run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:36:36 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:36:39 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:36:39 DEBUG : Looking for writers 2026/06/09 04:36:39 DEBUG : >WaitForWriters: --- FAIL: TestFileMethods (7.86s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:36:44 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:36:47 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:36:47 DEBUG : Looking for writers 2026/06/09 04:36:47 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:36:53 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:36:58 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:36:58 DEBUG : Looking for writers 2026/06/09 04:36:58 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:37:03 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:37:07 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:37:07 DEBUG : Looking for writers 2026/06/09 04:37:07 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:37:13 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:37:13 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:37:13 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:13 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:13 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:13 DEBUG : Config file has changed externally - reloading 2026/06/09 04:37:13 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:37:13 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:13 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:13 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:37:13 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:13 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:37:17 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:37:17 DEBUG : Looking for writers 2026/06/09 04:37:17 DEBUG : >WaitForWriters: 2026/06/09 04:37:17 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:37:23 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:37:23 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:37:23 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:23 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:37:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:37:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:23 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:37:29 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:37:29 DEBUG : Looking for writers 2026/06/09 04:37:29 DEBUG : >WaitForWriters: 2026/06/09 04:37:29 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:37:34 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:37:34 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:37:34 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:34 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:37:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:37:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:37:34 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:37:37 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:37:37 DEBUG : Looking for writers 2026/06/09 04:37:37 DEBUG : >WaitForWriters: 2026/06/09 04:37:37 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestFileSetModTime (57.43s) --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (9.51s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=false (9.72s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (9.61s) --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (10.15s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (10.72s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (7.73s) === RUN TestFileOpenRead run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:37:41 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:37:46 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:37:46 DEBUG : Looking for writers 2026/06/09 04:37:46 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenRead (10.80s) === RUN TestFileOpenWrite run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:37:52 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:37:56 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:37:56 DEBUG : Looking for writers 2026/06/09 04:37:56 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenWrite (8.44s) === RUN TestFileRemove run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:38:01 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:38:04 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:38:04 DEBUG : Looking for writers 2026/06/09 04:38:04 DEBUG : >WaitForWriters: --- FAIL: TestFileRemove (7.99s) === RUN TestFileRemoveAll run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:38:09 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:38:14 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:38:14 DEBUG : Looking for writers 2026/06/09 04:38:14 DEBUG : >WaitForWriters: --- FAIL: TestFileRemoveAll (10.64s) === RUN TestFileOpen run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:38:19 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:38:22 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:38:22 DEBUG : Looking for writers 2026/06/09 04:38:22 DEBUG : >WaitForWriters: --- FAIL: TestFileOpen (6.98s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:38:26 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:38:30 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:38:30 DEBUG : Looking for writers 2026/06/09 04:38:30 DEBUG : >WaitForWriters: === RUN TestFileRename/minimal,forceCache=false run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:38:34 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:38:34 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:38:34 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:34 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:38:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:38:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:34 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:38:39 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:38:39 DEBUG : Looking for writers 2026/06/09 04:38:39 DEBUG : >WaitForWriters: 2026/06/09 04:38:39 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting === RUN TestFileRename/minimal,forceCache=true run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:38:45 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:38:45 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:38:45 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:45 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:45 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:38:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:45 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:38:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:45 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:38:48 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:38:48 DEBUG : Looking for writers 2026/06/09 04:38:48 DEBUG : >WaitForWriters: 2026/06/09 04:38:48 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=false run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:38:51 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:38:51 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:38:51 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:51 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:51 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:51 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:38:51 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:51 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:51 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:38:51 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:51 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:38:54 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:38:54 DEBUG : Looking for writers 2026/06/09 04:38:54 DEBUG : >WaitForWriters: 2026/06/09 04:38:54 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=true run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:38:58 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:38:58 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:38:58 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:58 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:58 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:58 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:38:58 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:58 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:58 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:38:58 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:38:58 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:39:02 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:39:02 DEBUG : Looking for writers 2026/06/09 04:39:02 DEBUG : >WaitForWriters: 2026/06/09 04:39:02 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting === RUN TestFileRename/full,forceCache=false run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:39:09 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:39:09 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:39:09 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:09 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:09 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:09 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:39:09 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:09 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:09 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:39:09 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:09 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:39:13 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:39:13 DEBUG : Looking for writers 2026/06/09 04:39:13 DEBUG : >WaitForWriters: 2026/06/09 04:39:13 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestFileRename (51.24s) --- FAIL: TestFileRename/off,forceCache=false (8.28s) --- FAIL: TestFileRename/minimal,forceCache=false (10.12s) --- FAIL: TestFileRename/minimal,forceCache=true (6.51s) --- FAIL: TestFileRename/writes,forceCache=false (6.79s) --- FAIL: TestFileRename/writes,forceCache=true (10.94s) --- FAIL: TestFileRename/full,forceCache=false (8.60s) === RUN TestReadFileHandleMethods run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:39:17 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:39:20 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:39:20 DEBUG : Looking for writers 2026/06/09 04:39:20 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (6.93s) === RUN TestReadFileHandleSeek run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:39:24 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:39:29 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:39:29 DEBUG : Looking for writers 2026/06/09 04:39:29 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleSeek (10.30s) === RUN TestReadFileHandleReadAt run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:39:35 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:39:39 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:39:39 DEBUG : Looking for writers 2026/06/09 04:39:39 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (8.17s) === RUN TestReadFileHandleFlush run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:39:43 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:39:45 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:39:45 DEBUG : Looking for writers 2026/06/09 04:39:45 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleFlush (6.35s) === RUN TestReadFileHandleRelease run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:39:49 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:39:52 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:39:52 DEBUG : Looking for writers 2026/06/09 04:39:52 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (9.46s) === RUN TestRWFileHandleMethodsRead run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:39:59 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:39:59 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:39:59 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:59 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:59 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:59 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:39:59 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:59 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:59 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:39:59 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:39:59 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:04 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:40:04 DEBUG : Looking for writers 2026/06/09 04:40:04 DEBUG : >WaitForWriters: 2026/06/09 04:40:04 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (9.85s) === RUN TestRWFileHandleSeek run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:40:08 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:40:08 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:40:08 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:08 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:08 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:08 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:08 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:08 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:08 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:08 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:08 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:12 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:40:12 DEBUG : Looking for writers 2026/06/09 04:40:12 DEBUG : >WaitForWriters: 2026/06/09 04:40:12 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (8.22s) === RUN TestRWFileHandleReadAt run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:40:17 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:40:17 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:40:17 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:17 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:17 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:17 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:17 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:21 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:40:21 DEBUG : Looking for writers 2026/06/09 04:40:21 DEBUG : >WaitForWriters: 2026/06/09 04:40:21 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (10.38s) === RUN TestRWFileHandleFlushRead run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:40:27 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:40:27 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:40:27 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:27 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:27 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:31 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:40:31 DEBUG : Looking for writers 2026/06/09 04:40:31 DEBUG : >WaitForWriters: 2026/06/09 04:40:31 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleFlushRead (7.62s) === RUN TestRWFileHandleReleaseRead run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:40:35 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:40:35 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:40:35 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:35 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:35 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:35 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:35 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:35 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:35 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:35 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:35 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:38 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:40:38 DEBUG : Looking for writers 2026/06/09 04:40:38 DEBUG : >WaitForWriters: 2026/06/09 04:40:38 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (8.12s) === RUN TestRWFileHandleMethodsWrite run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:40:43 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:40:43 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:40:43 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:43 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:43 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:43 DEBUG : Config file has changed externally - reloading 2026/06/09 04:40:43 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:43 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:43 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:43 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:40:43 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:40:43 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:40:43 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/06/09 04:40:44 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:40:44 DEBUG : file1: newRWFileHandle: 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): openPending: 2026/06/09 04:40:44 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/06/09 04:40:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): >openPending: err= 2026/06/09 04:40:44 DEBUG : file1: >newRWFileHandle: err= 2026/06/09 04:40:44 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:40:44 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/06/09 04:40:44 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): _writeAt: size=5, off=0 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): >_writeAt: n=5, err= 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): _writeAt: size=7, off=5 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): >_writeAt: n=7, err= 2026/06/09 04:40:44 DEBUG : file1: vfs cache: truncate to size=11 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): close: 2026/06/09 04:40:44 DEBUG : file1: vfs cache: setting modification time to 2026-06-09 04:40:44.220566641 +0000 UTC m=+418.347065891 2026/06/09 04:40:44 INFO : file1: vfs cache: queuing for upload in 100ms 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): >close: err= 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): close: 2026/06/09 04:40:44 DEBUG : file1(0x3368812d0400): >close: err=file already closed 2026/06/09 04:40:44 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:40:44 DEBUG : Looking for writers 2026/06/09 04:40:44 DEBUG : file1: reading active writers 2026/06/09 04:40:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/06/09 04:40:44 DEBUG : Looking for writers 2026/06/09 04:40:44 DEBUG : file1: reading active writers 2026/06/09 04:40:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/06/09 04:40:44 DEBUG : Looking for writers 2026/06/09 04:40:44 DEBUG : file1: reading active writers 2026/06/09 04:40:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/06/09 04:40:44 DEBUG : Looking for writers 2026/06/09 04:40:44 DEBUG : file1: reading active writers 2026/06/09 04:40:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/06/09 04:40:44 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:40:44 DEBUG : Looking for writers 2026/06/09 04:40:44 DEBUG : file1: reading active writers 2026/06/09 04:40:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/06/09 04:40:44 DEBUG : Looking for writers 2026/06/09 04:40:44 DEBUG : file1: reading active writers 2026/06/09 04:40:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/06/09 04:40:44 DEBUG : Looking for writers 2026/06/09 04:40:44 DEBUG : file1: reading active writers 2026/06/09 04:40:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/06/09 04:40:45 DEBUG : Looking for writers 2026/06/09 04:40:45 DEBUG : file1: reading active writers 2026/06/09 04:40:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:46 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:46 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 "The file failed to upload." 2026/06/09 04:40:46 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:40:46 DEBUG : Looking for writers 2026/06/09 04:40:46 DEBUG : file1: reading active writers 2026/06/09 04:40:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:47 DEBUG : Looking for writers 2026/06/09 04:40:47 DEBUG : file1: reading active writers 2026/06/09 04:40:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:48 DEBUG : Looking for writers 2026/06/09 04:40:48 DEBUG : file1: reading active writers 2026/06/09 04:40:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:48 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:48 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 "The file failed to upload." 2026/06/09 04:40:48 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:40:49 DEBUG : Looking for writers 2026/06/09 04:40:49 DEBUG : file1: reading active writers 2026/06/09 04:40:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:50 DEBUG : Looking for writers 2026/06/09 04:40:50 DEBUG : file1: reading active writers 2026/06/09 04:40:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:50 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:50 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 "The file failed to upload." 2026/06/09 04:40:51 DEBUG : Looking for writers 2026/06/09 04:40:51 DEBUG : file1: reading active writers 2026/06/09 04:40:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:51 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:40:52 DEBUG : Looking for writers 2026/06/09 04:40:52 DEBUG : file1: reading active writers 2026/06/09 04:40:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:53 DEBUG : Looking for writers 2026/06/09 04:40:53 DEBUG : file1: reading active writers 2026/06/09 04:40:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:53 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:53 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 "The file failed to upload." 2026/06/09 04:40:54 DEBUG : Looking for writers 2026/06/09 04:40:54 DEBUG : file1: reading active writers 2026/06/09 04:40:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:55 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:40:55 DEBUG : Looking for writers 2026/06/09 04:40:55 DEBUG : file1: reading active writers 2026/06/09 04:40:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:56 DEBUG : Looking for writers 2026/06/09 04:40:56 DEBUG : file1: reading active writers 2026/06/09 04:40:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:57 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:40:57 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 "The file failed to upload." 2026/06/09 04:40:57 DEBUG : Looking for writers 2026/06/09 04:40:57 DEBUG : file1: reading active writers 2026/06/09 04:40:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:58 DEBUG : Looking for writers 2026/06/09 04:40:58 DEBUG : file1: reading active writers 2026/06/09 04:40:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:40:59 DEBUG : Looking for writers 2026/06/09 04:40:59 DEBUG : file1: reading active writers 2026/06/09 04:40:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:00 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:41:00 DEBUG : Looking for writers 2026/06/09 04:41:00 DEBUG : file1: reading active writers 2026/06/09 04:41:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:01 DEBUG : Looking for writers 2026/06/09 04:41:01 DEBUG : file1: reading active writers 2026/06/09 04:41:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:02 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:41:02 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 "The file failed to upload." 2026/06/09 04:41:02 DEBUG : Looking for writers 2026/06/09 04:41:02 DEBUG : file1: reading active writers 2026/06/09 04:41:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:03 DEBUG : Looking for writers 2026/06/09 04:41:03 DEBUG : file1: reading active writers 2026/06/09 04:41:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:04 DEBUG : Looking for writers 2026/06/09 04:41:04 DEBUG : file1: reading active writers 2026/06/09 04:41:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:05 DEBUG : Looking for writers 2026/06/09 04:41:05 DEBUG : file1: reading active writers 2026/06/09 04:41:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:06 DEBUG : Looking for writers 2026/06/09 04:41:06 DEBUG : file1: reading active writers 2026/06/09 04:41:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:07 DEBUG : Looking for writers 2026/06/09 04:41:07 DEBUG : file1: reading active writers 2026/06/09 04:41:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:08 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:41:08 DEBUG : Looking for writers 2026/06/09 04:41:08 DEBUG : file1: reading active writers 2026/06/09 04:41:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:09 DEBUG : Looking for writers 2026/06/09 04:41:09 DEBUG : file1: reading active writers 2026/06/09 04:41:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:10 DEBUG : Looking for writers 2026/06/09 04:41:10 DEBUG : file1: reading active writers 2026/06/09 04:41:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:10 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:41:10 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 "The file failed to upload." 2026/06/09 04:41:11 DEBUG : Looking for writers 2026/06/09 04:41:11 DEBUG : file1: reading active writers 2026/06/09 04:41:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:12 DEBUG : Looking for writers 2026/06/09 04:41:12 DEBUG : file1: reading active writers 2026/06/09 04:41:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:13 DEBUG : Looking for writers 2026/06/09 04:41:13 DEBUG : file1: reading active writers 2026/06/09 04:41:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:14 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x336881552e00 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x336881406008 notify:{wait:0 notify:0 lock:0 head: tail:} checker:56523938095168} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14015769607640814705 ext:418347065891 loc:0x4733400} ATime:{wall:14015769607640836698 ext:418347087903 loc:0x4733400} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/06/09 04:41:14 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/5 2026/06/09 04:41:23 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:41:25 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:41:25 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 "The file failed to upload." fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 8s for list eventual consistency: 4/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 16s for list eventual consistency: 5/5 2026/06/09 04:41:43 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2026/06/09 04:41:43 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11) 2026/06/09 04:41:51 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:41:52 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:41:52 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 "The file failed to upload." 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/06/09 04:41:54 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:41:54 DEBUG : Looking for writers 2026/06/09 04:41:54 DEBUG : file1: reading active writers 2026/06/09 04:41:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/06/09 04:41:54 DEBUG : Looking for writers 2026/06/09 04:41:54 DEBUG : file1: reading active writers 2026/06/09 04:41:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/06/09 04:41:54 DEBUG : Looking for writers 2026/06/09 04:41:54 DEBUG : file1: reading active writers 2026/06/09 04:41:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/06/09 04:41:55 DEBUG : Looking for writers 2026/06/09 04:41:55 DEBUG : file1: reading active writers 2026/06/09 04:41:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/06/09 04:41:55 DEBUG : Looking for writers 2026/06/09 04:41:55 DEBUG : file1: reading active writers 2026/06/09 04:41:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/06/09 04:41:55 DEBUG : Looking for writers 2026/06/09 04:41:55 DEBUG : file1: reading active writers 2026/06/09 04:41:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/06/09 04:41:55 DEBUG : Looking for writers 2026/06/09 04:41:55 DEBUG : file1: reading active writers 2026/06/09 04:41:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/06/09 04:41:56 DEBUG : Looking for writers 2026/06/09 04:41:56 DEBUG : file1: reading active writers 2026/06/09 04:41:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:57 DEBUG : Looking for writers 2026/06/09 04:41:57 DEBUG : file1: reading active writers 2026/06/09 04:41:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:58 DEBUG : Looking for writers 2026/06/09 04:41:58 DEBUG : file1: reading active writers 2026/06/09 04:41:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:41:59 DEBUG : Looking for writers 2026/06/09 04:41:59 DEBUG : file1: reading active writers 2026/06/09 04:41:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:00 DEBUG : Looking for writers 2026/06/09 04:42:00 DEBUG : file1: reading active writers 2026/06/09 04:42:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:01 DEBUG : Looking for writers 2026/06/09 04:42:01 DEBUG : file1: reading active writers 2026/06/09 04:42:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:02 DEBUG : Looking for writers 2026/06/09 04:42:02 DEBUG : file1: reading active writers 2026/06/09 04:42:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:03 DEBUG : Looking for writers 2026/06/09 04:42:03 DEBUG : file1: reading active writers 2026/06/09 04:42:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:04 DEBUG : Looking for writers 2026/06/09 04:42:04 DEBUG : file1: reading active writers 2026/06/09 04:42:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:05 DEBUG : Looking for writers 2026/06/09 04:42:05 DEBUG : file1: reading active writers 2026/06/09 04:42:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:06 DEBUG : Looking for writers 2026/06/09 04:42:06 DEBUG : file1: reading active writers 2026/06/09 04:42:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:07 DEBUG : Looking for writers 2026/06/09 04:42:07 DEBUG : file1: reading active writers 2026/06/09 04:42:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:08 DEBUG : Looking for writers 2026/06/09 04:42:08 DEBUG : file1: reading active writers 2026/06/09 04:42:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:09 DEBUG : Looking for writers 2026/06/09 04:42:09 DEBUG : file1: reading active writers 2026/06/09 04:42:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:10 DEBUG : Looking for writers 2026/06/09 04:42:10 DEBUG : file1: reading active writers 2026/06/09 04:42:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:11 DEBUG : Looking for writers 2026/06/09 04:42:11 DEBUG : file1: reading active writers 2026/06/09 04:42:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:12 DEBUG : Looking for writers 2026/06/09 04:42:12 DEBUG : file1: reading active writers 2026/06/09 04:42:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:13 DEBUG : Looking for writers 2026/06/09 04:42:13 DEBUG : file1: reading active writers 2026/06/09 04:42:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:14 DEBUG : Looking for writers 2026/06/09 04:42:14 DEBUG : file1: reading active writers 2026/06/09 04:42:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:15 DEBUG : Looking for writers 2026/06/09 04:42:15 DEBUG : file1: reading active writers 2026/06/09 04:42:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:16 DEBUG : Looking for writers 2026/06/09 04:42:16 DEBUG : file1: reading active writers 2026/06/09 04:42:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:17 DEBUG : Looking for writers 2026/06/09 04:42:17 DEBUG : file1: reading active writers 2026/06/09 04:42:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:18 DEBUG : Looking for writers 2026/06/09 04:42:18 DEBUG : file1: reading active writers 2026/06/09 04:42:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:19 DEBUG : Looking for writers 2026/06/09 04:42:19 DEBUG : file1: reading active writers 2026/06/09 04:42:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:20 DEBUG : Looking for writers 2026/06/09 04:42:20 DEBUG : file1: reading active writers 2026/06/09 04:42:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:21 DEBUG : Looking for writers 2026/06/09 04:42:21 DEBUG : file1: reading active writers 2026/06/09 04:42:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:22 DEBUG : Looking for writers 2026/06/09 04:42:22 DEBUG : file1: reading active writers 2026/06/09 04:42:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:23 DEBUG : Looking for writers 2026/06/09 04:42:23 DEBUG : file1: reading active writers 2026/06/09 04:42:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:24 DEBUG : Looking for writers 2026/06/09 04:42:24 DEBUG : file1: reading active writers 2026/06/09 04:42:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:24 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x336881552e00 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x336881406008 notify:{wait:0 notify:0 lock:0 head: tail:} checker:56523938095168} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14015769607640814705 ext:418347065891 loc:0x4733400} ATime:{wall:14015769607640836698 ext:418347087903 loc:0x4733400} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/06/09 04:42:24 DEBUG : >WaitForWriters: 2026/06/09 04:42:24 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsWrite (104.55s) === RUN TestRWFileHandleWriteAt run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:42:27 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:42:27 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:42:27 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:42:27 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:42:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:42:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:42:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:42:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:42:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:42:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:42:27 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:42:27 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/06/09 04:42:29 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:42:29 DEBUG : file1: newRWFileHandle: 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): openPending: 2026/06/09 04:42:29 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/06/09 04:42:29 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): >openPending: err= 2026/06/09 04:42:29 DEBUG : file1: >newRWFileHandle: err= 2026/06/09 04:42:29 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:42:29 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/06/09 04:42:29 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): _writeAt: size=7, off=0 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): >_writeAt: n=7, err= 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): _writeAt: size=6, off=5 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): >_writeAt: n=6, err= 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): close: 2026/06/09 04:42:29 DEBUG : file1: vfs cache: setting modification time to 2026-06-09 04:42:29.268683638 +0000 UTC m=+523.395182897 2026/06/09 04:42:29 INFO : file1: vfs cache: queuing for upload in 100ms 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): >close: err= 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): _writeAt: size=5, off=0 2026/06/09 04:42:29 DEBUG : file1(0x3368814c6100): >_writeAt: n=0, err=file already closed 2026/06/09 04:42:29 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:42:29 DEBUG : Looking for writers 2026/06/09 04:42:29 DEBUG : file1: reading active writers 2026/06/09 04:42:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/06/09 04:42:29 DEBUG : Looking for writers 2026/06/09 04:42:29 DEBUG : file1: reading active writers 2026/06/09 04:42:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/06/09 04:42:29 DEBUG : Looking for writers 2026/06/09 04:42:29 DEBUG : file1: reading active writers 2026/06/09 04:42:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/06/09 04:42:29 DEBUG : Looking for writers 2026/06/09 04:42:29 DEBUG : file1: reading active writers 2026/06/09 04:42:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/06/09 04:42:29 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:42:29 DEBUG : Looking for writers 2026/06/09 04:42:29 DEBUG : file1: reading active writers 2026/06/09 04:42:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/06/09 04:42:29 DEBUG : Looking for writers 2026/06/09 04:42:29 DEBUG : file1: reading active writers 2026/06/09 04:42:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/06/09 04:42:29 DEBUG : Looking for writers 2026/06/09 04:42:29 DEBUG : file1: reading active writers 2026/06/09 04:42:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/06/09 04:42:30 DEBUG : Looking for writers 2026/06/09 04:42:30 DEBUG : file1: reading active writers 2026/06/09 04:42:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:31 DEBUG : Looking for writers 2026/06/09 04:42:31 DEBUG : file1: reading active writers 2026/06/09 04:42:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:31 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:42:31 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 "The file failed to upload." 2026/06/09 04:42:31 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:42:32 DEBUG : Looking for writers 2026/06/09 04:42:32 DEBUG : file1: reading active writers 2026/06/09 04:42:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:33 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:42:33 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 "The file failed to upload." 2026/06/09 04:42:33 DEBUG : Looking for writers 2026/06/09 04:42:33 DEBUG : file1: reading active writers 2026/06/09 04:42:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:33 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:42:34 DEBUG : Looking for writers 2026/06/09 04:42:34 DEBUG : file1: reading active writers 2026/06/09 04:42:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:34 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:42:34 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 "The file failed to upload." 2026/06/09 04:42:35 DEBUG : Looking for writers 2026/06/09 04:42:35 DEBUG : file1: reading active writers 2026/06/09 04:42:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:35 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:42:36 DEBUG : Looking for writers 2026/06/09 04:42:36 DEBUG : file1: reading active writers 2026/06/09 04:42:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:37 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:42:37 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 "The file failed to upload." 2026/06/09 04:42:37 DEBUG : Looking for writers 2026/06/09 04:42:37 DEBUG : file1: reading active writers 2026/06/09 04:42:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:38 DEBUG : Looking for writers 2026/06/09 04:42:38 DEBUG : file1: reading active writers 2026/06/09 04:42:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:38 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:42:39 DEBUG : Looking for writers 2026/06/09 04:42:39 DEBUG : file1: reading active writers 2026/06/09 04:42:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:40 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:42: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 "The file failed to upload." 2026/06/09 04:42:40 DEBUG : Looking for writers 2026/06/09 04:42:40 DEBUG : file1: reading active writers 2026/06/09 04:42:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:41 DEBUG : Looking for writers 2026/06/09 04:42:41 DEBUG : file1: reading active writers 2026/06/09 04:42:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:42 DEBUG : Looking for writers 2026/06/09 04:42:42 DEBUG : file1: reading active writers 2026/06/09 04:42:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:43 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:42:43 DEBUG : Looking for writers 2026/06/09 04:42:43 DEBUG : file1: reading active writers 2026/06/09 04:42:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:44 DEBUG : Looking for writers 2026/06/09 04:42:44 DEBUG : file1: reading active writers 2026/06/09 04:42:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:45 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:42:45 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 "The file failed to upload." 2026/06/09 04:42:45 DEBUG : Looking for writers 2026/06/09 04:42:45 DEBUG : file1: reading active writers 2026/06/09 04:42:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:46 DEBUG : Looking for writers 2026/06/09 04:42:46 DEBUG : file1: reading active writers 2026/06/09 04:42:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:47 DEBUG : Looking for writers 2026/06/09 04:42:47 DEBUG : file1: reading active writers 2026/06/09 04:42:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:48 DEBUG : Looking for writers 2026/06/09 04:42:48 DEBUG : file1: reading active writers 2026/06/09 04:42:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:49 DEBUG : Looking for writers 2026/06/09 04:42:49 DEBUG : file1: reading active writers 2026/06/09 04:42:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:50 DEBUG : Looking for writers 2026/06/09 04:42:50 DEBUG : file1: reading active writers 2026/06/09 04:42:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:51 DEBUG : Looking for writers 2026/06/09 04:42:51 DEBUG : file1: reading active writers 2026/06/09 04:42:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:51 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:42:52 DEBUG : Looking for writers 2026/06/09 04:42:52 DEBUG : file1: reading active writers 2026/06/09 04:42:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:53 DEBUG : Looking for writers 2026/06/09 04:42:53 DEBUG : file1: reading active writers 2026/06/09 04:42:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:53 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:42:53 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 "The file failed to upload." 2026/06/09 04:42:54 DEBUG : Looking for writers 2026/06/09 04:42:54 DEBUG : file1: reading active writers 2026/06/09 04:42:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:55 DEBUG : Looking for writers 2026/06/09 04:42:55 DEBUG : file1: reading active writers 2026/06/09 04:42:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:56 DEBUG : Looking for writers 2026/06/09 04:42:56 DEBUG : file1: reading active writers 2026/06/09 04:42:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:57 DEBUG : Looking for writers 2026/06/09 04:42:57 DEBUG : file1: reading active writers 2026/06/09 04:42:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:58 DEBUG : Looking for writers 2026/06/09 04:42:58 DEBUG : file1: reading active writers 2026/06/09 04:42:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:42:59 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x336881553400 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x336881406248 notify:{wait:0 notify:0 lock:0 head: tail:} checker:56523938095744} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14015769720431823222 ext:523395182897 loc:0x4733400} ATime:{wall:14015769720431843270 ext:523395202934 loc:0x4733400} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/06/09 04:42:59 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/5 2026/06/09 04:43:06 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:43:08 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:43:08 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 "The file failed to upload." fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 8s for list eventual consistency: 4/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 16s for list eventual consistency: 5/5 2026/06/09 04:43:27 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2026/06/09 04:43:27 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11) 2026/06/09 04:43:34 DEBUG : file1: vfs cache: starting upload 2026/06/09 04:43:36 ERROR : file1: Failed to copy: failed to upload file: Error "The file failed to upload." 2026/06/09 04:43:36 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 "The file failed to upload." 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/06/09 04:43:38 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:43:38 DEBUG : Looking for writers 2026/06/09 04:43:38 DEBUG : file1: reading active writers 2026/06/09 04:43:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/06/09 04:43:38 DEBUG : Looking for writers 2026/06/09 04:43:38 DEBUG : file1: reading active writers 2026/06/09 04:43:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/06/09 04:43:38 DEBUG : Looking for writers 2026/06/09 04:43:38 DEBUG : file1: reading active writers 2026/06/09 04:43:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/06/09 04:43:38 DEBUG : Looking for writers 2026/06/09 04:43:38 DEBUG : file1: reading active writers 2026/06/09 04:43:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/06/09 04:43:38 DEBUG : Looking for writers 2026/06/09 04:43:38 DEBUG : file1: reading active writers 2026/06/09 04:43:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/06/09 04:43:38 DEBUG : Looking for writers 2026/06/09 04:43:38 DEBUG : file1: reading active writers 2026/06/09 04:43:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/06/09 04:43:39 DEBUG : Looking for writers 2026/06/09 04:43:39 DEBUG : file1: reading active writers 2026/06/09 04:43:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/06/09 04:43:39 DEBUG : Looking for writers 2026/06/09 04:43:39 DEBUG : file1: reading active writers 2026/06/09 04:43:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:40 DEBUG : Looking for writers 2026/06/09 04:43:40 DEBUG : file1: reading active writers 2026/06/09 04:43:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:41 DEBUG : Looking for writers 2026/06/09 04:43:41 DEBUG : file1: reading active writers 2026/06/09 04:43:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:42 DEBUG : Looking for writers 2026/06/09 04:43:42 DEBUG : file1: reading active writers 2026/06/09 04:43:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:43 DEBUG : Looking for writers 2026/06/09 04:43:43 DEBUG : file1: reading active writers 2026/06/09 04:43:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:44 DEBUG : Looking for writers 2026/06/09 04:43:44 DEBUG : file1: reading active writers 2026/06/09 04:43:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:45 DEBUG : Looking for writers 2026/06/09 04:43:45 DEBUG : file1: reading active writers 2026/06/09 04:43:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:46 DEBUG : Looking for writers 2026/06/09 04:43:46 DEBUG : file1: reading active writers 2026/06/09 04:43:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:47 DEBUG : Looking for writers 2026/06/09 04:43:47 DEBUG : file1: reading active writers 2026/06/09 04:43:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:48 DEBUG : forgetting directory cache 2026/06/09 04:43:48 DEBUG : Looking for writers 2026/06/09 04:43:48 DEBUG : file1: reading active writers 2026/06/09 04:43:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:49 DEBUG : Looking for writers 2026/06/09 04:43:49 DEBUG : file1: reading active writers 2026/06/09 04:43:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:50 DEBUG : Looking for writers 2026/06/09 04:43:50 DEBUG : file1: reading active writers 2026/06/09 04:43:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:51 DEBUG : Looking for writers 2026/06/09 04:43:51 DEBUG : file1: reading active writers 2026/06/09 04:43:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:52 DEBUG : Looking for writers 2026/06/09 04:43:52 DEBUG : file1: reading active writers 2026/06/09 04:43:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:53 DEBUG : Looking for writers 2026/06/09 04:43:53 DEBUG : file1: reading active writers 2026/06/09 04:43:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:54 DEBUG : Looking for writers 2026/06/09 04:43:54 DEBUG : file1: reading active writers 2026/06/09 04:43:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:55 DEBUG : Looking for writers 2026/06/09 04:43:55 DEBUG : file1: reading active writers 2026/06/09 04:43:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:56 DEBUG : Looking for writers 2026/06/09 04:43:56 DEBUG : file1: reading active writers 2026/06/09 04:43:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:57 DEBUG : Looking for writers 2026/06/09 04:43:57 DEBUG : file1: reading active writers 2026/06/09 04:43:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:58 DEBUG : Looking for writers 2026/06/09 04:43:58 DEBUG : file1: reading active writers 2026/06/09 04:43:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:43:59 DEBUG : forgetting directory cache 2026/06/09 04:43:59 DEBUG : Looking for writers 2026/06/09 04:43:59 DEBUG : file1: reading active writers 2026/06/09 04:43:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:00 DEBUG : Looking for writers 2026/06/09 04:44:00 DEBUG : file1: reading active writers 2026/06/09 04:44:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:01 DEBUG : Looking for writers 2026/06/09 04:44:01 DEBUG : file1: reading active writers 2026/06/09 04:44:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:02 DEBUG : Looking for writers 2026/06/09 04:44:02 DEBUG : file1: reading active writers 2026/06/09 04:44:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:03 DEBUG : Looking for writers 2026/06/09 04:44:03 DEBUG : file1: reading active writers 2026/06/09 04:44:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:04 DEBUG : Looking for writers 2026/06/09 04:44:04 DEBUG : file1: reading active writers 2026/06/09 04:44:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:05 DEBUG : Looking for writers 2026/06/09 04:44:05 DEBUG : file1: reading active writers 2026/06/09 04:44:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:06 DEBUG : Looking for writers 2026/06/09 04:44:06 DEBUG : file1: reading active writers 2026/06/09 04:44:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:07 DEBUG : Looking for writers 2026/06/09 04:44:07 DEBUG : file1: reading active writers 2026/06/09 04:44:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:08 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x336881553400 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x336881406248 notify:{wait:0 notify:0 lock:0 head: tail:} checker:56523938095744} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:14015769720431823222 ext:523395182897 loc:0x4733400} ATime:{wall:14015769720431843270 ext:523395202934 loc:0x4733400} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false graceTimer:}, } 2026/06/09 04:44:08 DEBUG : >WaitForWriters: 2026/06/09 04:44:08 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting 2026/06/09 04:44:10 DEBUG : forgetting directory cache --- FAIL: TestRWFileHandleWriteAt (103.53s) === RUN TestRWFileHandleSizeTruncateExisting run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:44:11 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:44:11 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:44:11 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:11 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:11 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:11 DEBUG : Config file has changed externally - reloading 2026/06/09 04:44:11 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:44:11 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:11 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:11 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:44:11 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:11 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:44:15 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:44:15 DEBUG : Looking for writers 2026/06/09 04:44:15 DEBUG : >WaitForWriters: 2026/06/09 04:44:15 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting 2026/06/09 04:44:19 DEBUG : forgetting directory cache --- FAIL: TestRWFileHandleSizeTruncateExisting (10.05s) === RUN TestRWFileHandleSizeCreateExisting run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:44:21 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:44:21 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:44:21 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:21 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:44:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:44:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:21 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:44:26 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:44:26 DEBUG : Looking for writers 2026/06/09 04:44:26 DEBUG : >WaitForWriters: 2026/06/09 04:44:26 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting 2026/06/09 04:44:30 DEBUG : forgetting directory cache --- FAIL: TestRWFileHandleSizeCreateExisting (9.87s) === RUN TestRWFileModTimeWithOpenWriters run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:44:31 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:44:31 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: root is "/home/rclone/.cache/rclone" 2026/06/09 04:44:31 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:31 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:31 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:31 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:44:31 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:31 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:31 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/06/09 04:44:31 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-kuwutil9meqo" 2026/06/09 04:44:31 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:44:31 INFO : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/06/09 04:44:32 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:44:32 DEBUG : file1: newRWFileHandle: 2026/06/09 04:44:32 DEBUG : file1(0x336881ad6680): openPending: 2026/06/09 04:44:32 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/06/09 04:44:32 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:44:32 DEBUG : file1(0x336881ad6680): >openPending: err= 2026/06/09 04:44:32 DEBUG : file1: >newRWFileHandle: err= 2026/06/09 04:44:32 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:44:32 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/06/09 04:44:32 DEBUG : file1: >OpenFile: fd=file1 (rw), err= run.go:303: Failed to put "time_test" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:44:34 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:44:34 DEBUG : Looking for writers 2026/06/09 04:44:34 DEBUG : file1: reading active writers 2026/06/09 04:44:34 DEBUG : file1: active writers 1 2026/06/09 04:44:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 10ms 2026/06/09 04:44:34 DEBUG : Looking for writers 2026/06/09 04:44:34 DEBUG : file1: reading active writers 2026/06/09 04:44:34 DEBUG : file1: active writers 1 2026/06/09 04:44:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 20ms 2026/06/09 04:44:34 DEBUG : Looking for writers 2026/06/09 04:44:34 DEBUG : file1: reading active writers 2026/06/09 04:44:34 DEBUG : file1: active writers 1 2026/06/09 04:44:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 40ms 2026/06/09 04:44:34 DEBUG : Looking for writers 2026/06/09 04:44:34 DEBUG : file1: reading active writers 2026/06/09 04:44:34 DEBUG : file1: active writers 1 2026/06/09 04:44:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 80ms 2026/06/09 04:44:34 DEBUG : Looking for writers 2026/06/09 04:44:34 DEBUG : file1: reading active writers 2026/06/09 04:44:34 DEBUG : file1: active writers 1 2026/06/09 04:44:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 160ms 2026/06/09 04:44:34 DEBUG : Looking for writers 2026/06/09 04:44:34 DEBUG : file1: reading active writers 2026/06/09 04:44:34 DEBUG : file1: active writers 1 2026/06/09 04:44:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 320ms 2026/06/09 04:44:34 DEBUG : Looking for writers 2026/06/09 04:44:34 DEBUG : file1: reading active writers 2026/06/09 04:44:34 DEBUG : file1: active writers 1 2026/06/09 04:44:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 640ms 2026/06/09 04:44:35 DEBUG : Looking for writers 2026/06/09 04:44:35 DEBUG : file1: reading active writers 2026/06/09 04:44:35 DEBUG : file1: active writers 1 2026/06/09 04:44:35 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:36 DEBUG : Looking for writers 2026/06/09 04:44:36 DEBUG : file1: reading active writers 2026/06/09 04:44:36 DEBUG : file1: active writers 1 2026/06/09 04:44:36 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:37 DEBUG : Looking for writers 2026/06/09 04:44:37 DEBUG : file1: reading active writers 2026/06/09 04:44:37 DEBUG : file1: active writers 1 2026/06/09 04:44:37 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:38 DEBUG : Looking for writers 2026/06/09 04:44:38 DEBUG : file1: reading active writers 2026/06/09 04:44:38 DEBUG : file1: active writers 1 2026/06/09 04:44:38 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:38 DEBUG : forgetting directory cache 2026/06/09 04:44:39 DEBUG : Looking for writers 2026/06/09 04:44:39 DEBUG : file1: reading active writers 2026/06/09 04:44:39 DEBUG : file1: active writers 1 2026/06/09 04:44:39 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:40 DEBUG : Looking for writers 2026/06/09 04:44:40 DEBUG : file1: reading active writers 2026/06/09 04:44:40 DEBUG : file1: active writers 1 2026/06/09 04:44:40 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:41 DEBUG : Looking for writers 2026/06/09 04:44:41 DEBUG : file1: reading active writers 2026/06/09 04:44:41 DEBUG : file1: active writers 1 2026/06/09 04:44:41 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:42 DEBUG : Looking for writers 2026/06/09 04:44:42 DEBUG : file1: reading active writers 2026/06/09 04:44:42 DEBUG : file1: active writers 1 2026/06/09 04:44:42 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:43 DEBUG : Looking for writers 2026/06/09 04:44:43 DEBUG : file1: reading active writers 2026/06/09 04:44:43 DEBUG : file1: active writers 1 2026/06/09 04:44:43 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:44 DEBUG : Looking for writers 2026/06/09 04:44:44 DEBUG : file1: reading active writers 2026/06/09 04:44:44 DEBUG : file1: active writers 1 2026/06/09 04:44:44 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:45 DEBUG : Looking for writers 2026/06/09 04:44:45 DEBUG : file1: reading active writers 2026/06/09 04:44:45 DEBUG : file1: active writers 1 2026/06/09 04:44:45 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:46 DEBUG : Looking for writers 2026/06/09 04:44:46 DEBUG : file1: reading active writers 2026/06/09 04:44:46 DEBUG : file1: active writers 1 2026/06/09 04:44:46 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:47 DEBUG : Looking for writers 2026/06/09 04:44:47 DEBUG : file1: reading active writers 2026/06/09 04:44:47 DEBUG : file1: active writers 1 2026/06/09 04:44:47 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:48 DEBUG : forgetting directory cache 2026/06/09 04:44:48 DEBUG : Looking for writers 2026/06/09 04:44:48 DEBUG : file1: reading active writers 2026/06/09 04:44:48 DEBUG : file1: active writers 1 2026/06/09 04:44:48 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:49 DEBUG : Looking for writers 2026/06/09 04:44:49 DEBUG : file1: reading active writers 2026/06/09 04:44:49 DEBUG : file1: active writers 1 2026/06/09 04:44:49 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:50 DEBUG : Looking for writers 2026/06/09 04:44:50 DEBUG : file1: reading active writers 2026/06/09 04:44:50 DEBUG : file1: active writers 1 2026/06/09 04:44:50 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:51 DEBUG : Looking for writers 2026/06/09 04:44:51 DEBUG : file1: reading active writers 2026/06/09 04:44:51 DEBUG : file1: active writers 1 2026/06/09 04:44:51 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:52 DEBUG : Looking for writers 2026/06/09 04:44:52 DEBUG : file1: reading active writers 2026/06/09 04:44:52 DEBUG : file1: active writers 1 2026/06/09 04:44:52 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:53 DEBUG : Looking for writers 2026/06/09 04:44:53 DEBUG : file1: reading active writers 2026/06/09 04:44:53 DEBUG : file1: active writers 1 2026/06/09 04:44:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:54 DEBUG : Looking for writers 2026/06/09 04:44:54 DEBUG : file1: reading active writers 2026/06/09 04:44:54 DEBUG : file1: active writers 1 2026/06/09 04:44:54 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:55 DEBUG : Looking for writers 2026/06/09 04:44:55 DEBUG : file1: reading active writers 2026/06/09 04:44:55 DEBUG : file1: active writers 1 2026/06/09 04:44:55 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:56 DEBUG : Looking for writers 2026/06/09 04:44:56 DEBUG : file1: reading active writers 2026/06/09 04:44:56 DEBUG : file1: active writers 1 2026/06/09 04:44:56 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:57 DEBUG : Looking for writers 2026/06/09 04:44:57 DEBUG : file1: reading active writers 2026/06/09 04:44:57 DEBUG : file1: active writers 1 2026/06/09 04:44:57 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:58 DEBUG : Looking for writers 2026/06/09 04:44:58 DEBUG : file1: reading active writers 2026/06/09 04:44:58 DEBUG : file1: active writers 1 2026/06/09 04:44:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:44:58 DEBUG : forgetting directory cache 2026/06/09 04:44:59 DEBUG : Looking for writers 2026/06/09 04:44:59 DEBUG : file1: reading active writers 2026/06/09 04:44:59 DEBUG : file1: active writers 1 2026/06/09 04:44:59 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:45:00 DEBUG : Looking for writers 2026/06/09 04:45:00 DEBUG : file1: reading active writers 2026/06/09 04:45:00 DEBUG : file1: active writers 1 2026/06/09 04:45:00 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:45:01 DEBUG : Looking for writers 2026/06/09 04:45:01 DEBUG : file1: reading active writers 2026/06/09 04:45:01 DEBUG : file1: active writers 1 2026/06/09 04:45:01 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:45:02 DEBUG : Looking for writers 2026/06/09 04:45:02 DEBUG : file1: reading active writers 2026/06/09 04:45:02 DEBUG : file1: active writers 1 2026/06/09 04:45:02 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:45:03 DEBUG : Looking for writers 2026/06/09 04:45:03 DEBUG : file1: reading active writers 2026/06/09 04:45:03 DEBUG : file1: active writers 1 2026/06/09 04:45:03 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2026/06/09 04:45:04 ERROR : Exiting even though 1 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0x336881128700 mu:{_:{} mu:{state:0 sema:0}} cond:{noCopy:{} L:0x336881adfd48 notify:{wait:0 notify:0 lock:0 head: tail:} checker:56523945278848} name:file1 opens:1 downloaders: o: fd:0x336880d82400 info:{ModTime:{wall:14015769852490111038 ext:646383226350 loc:0x4733400} ATime:{wall:14015769852490111038 ext:646383226350 loc:0x4733400} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:0 pendingAccesses:0 modified:true beingReset:false graceTimer:}, } 2026/06/09 04:45:04 DEBUG : >WaitForWriters: 2026/06/09 04:45:04 DEBUG : drime root 'rclone-test-kuwutil9meqo': vfs cache: cleaner exiting --- FAIL: TestRWFileModTimeWithOpenWriters (34.78s) === RUN TestCaseSensitivity run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:45:06 DEBUG : forgetting directory cache run.go:303: Failed to put "FiLeA" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." --- FAIL: TestCaseSensitivity (4.24s) === RUN TestUnicodeNormalization run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" run.go:303: Failed to put "normal name with no special characters.txt" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." --- FAIL: TestUnicodeNormalization (4.50s) === RUN TestVFSStat run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:45:14 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:45:17 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:45:17 DEBUG : Looking for writers 2026/06/09 04:45:17 DEBUG : >WaitForWriters: 2026/06/09 04:45:17 DEBUG : forgetting directory cache --- FAIL: TestVFSStat (4.12s) === RUN TestVFSStatParent run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:45:18 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:45:20 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:45:20 DEBUG : Looking for writers 2026/06/09 04:45:20 DEBUG : >WaitForWriters: --- FAIL: TestVFSStatParent (2.67s) === RUN TestVFSOpenFile run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:45:21 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "file1" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:45:22 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:45:22 DEBUG : Looking for writers 2026/06/09 04:45:22 DEBUG : >WaitForWriters: --- FAIL: TestVFSOpenFile (2.64s) === RUN TestVFSRename run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:45:24 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file2" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:45:27 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:45:27 DEBUG : Looking for writers 2026/06/09 04:45:27 DEBUG : >WaitForWriters: 2026/06/09 04:45:27 DEBUG : forgetting directory cache --- FAIL: TestVFSRename (7.60s) === RUN TestWriteFileHandleMethods run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:45:31 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:45:31 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:45:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:45:33 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:45:33 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:45:33 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:45:33 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:45:33 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2026/06/09 04:45:33 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2026/06/09 04:45:33 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2026/06/09 04:45:33 DEBUG : drime root 'rclone-test-kuwutil9meqo': File to upload is small (5 bytes), uploading instead of streaming 2026/06/09 04:45:34 DEBUG : forgetting directory cache 2026/06/09 04:45:36 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "The file failed to upload." 2026/06/09 04:45:36 DEBUG : file1: Remove: 2026/06/09 04:45:36 DEBUG : Added virtual directory entry vDel: "file1" 2026/06/09 04:45:36 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 "The file failed to upload." 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/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/5 2026/06/09 04:45:44 DEBUG : forgetting directory cache fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 8s for list eventual consistency: 4/5 2026/06/09 04:45:52 DEBUG : forgetting directory cache fstest.go:301: Flushing the directory cache 2026/06/09 04:46:00 DEBUG : forgetting directory cache fstest.go:298: Sleeping for 16s for list eventual consistency: 5/5 2026/06/09 04:46:09 DEBUG : forgetting directory cache 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/06/09 04:46:17 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:46:17 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:46:17 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:17 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:46:17 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:46:17 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:17 DEBUG : drime root 'rclone-test-kuwutil9meqo': File to upload is small (0 bytes), uploading instead of streaming 2026/06/09 04:46:17 DEBUG : forgetting directory cache 2026/06/09 04:46:20 DEBUG : file1: size = 0 OK 2026/06/09 04:46:20 NOTICE: drime root 'rclone-test-kuwutil9meqo': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/06/09 04:46:20 DEBUG : file1: Size of src and dst objects identical 2026/06/09 04:46:20 DEBUG : Added virtual directory entry vAddFile: "file1" 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{"file1,0,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=13) "file1,5,false" + (string) (len=13) "file1,0,false" } Test: TestWriteFileHandleMethods 2026/06/09 04:46:20 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:46:20 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:46:20 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:20 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:46:20 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:46:20 ERROR : file1: WriteFileHandle: Can't open for write without O_TRUNC on existing file without --vfs-cache-mode >= writes 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:173 Error: Not equal: expected: []string{"file1,5,false"} actual : []string{"file1,0,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=13) "file1,5,false" + (string) (len=13) "file1,0,false" } Test: TestWriteFileHandleMethods 2026/06/09 04:46:20 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2026/06/09 04:46:20 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2026/06/09 04:46:20 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:20 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:46:20 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:46:20 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:20 DEBUG : drime root 'rclone-test-kuwutil9meqo': File to upload is small (0 bytes), uploading instead of streaming 2026/06/09 04:46:22 DEBUG : file1: Removing old object on successful upload 2026/06/09 04:46:24 DEBUG : file1: Old object already deleted, safely ignoring 2026/06/09 04:46:24 DEBUG : file1: size = 0 OK 2026/06/09 04:46:24 DEBUG : file1: Size of src and dst objects identical 2026/06/09 04:46:24 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:24 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2026/06/09 04:46:24 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2026/06/09 04:46:24 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:24 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:46:24 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:46:24 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:24 DEBUG : drime root 'rclone-test-kuwutil9meqo': File to upload is small (7 bytes), uploading instead of streaming 2026/06/09 04:46:26 DEBUG : forgetting directory cache 2026/06/09 04:46:26 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "The file failed to upload." write_test.go:190: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:190 Error: Received unexpected error: failed to upload file: Error "The file failed to upload." 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:191 Error: Not equal: expected: []string{"file1,7,false"} actual : []string{"file1,0,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=13) "file1,7,false" + (string) (len=13) "file1,0,false" } Test: TestWriteFileHandleMethods 2026/06/09 04:46:26 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:46:26 DEBUG : Looking for writers 2026/06/09 04:46:26 DEBUG : file1: reading active writers 2026/06/09 04:46:26 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleMethods (57.71s) === RUN TestWriteFileHandleWriteAt run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:46:29 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:46:29 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:46:30 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:46:30 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:30 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:46:30 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:46:30 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:46:30 DEBUG : file1: waiting for in-sequence write to 100 for 1s 2026/06/09 04:46:31 DEBUG : file1: aborting in-sequence write wait, off=100 2026/06/09 04:46:31 DEBUG : file1: failed to wait for in-sequence write to 100 2026/06/09 04:46:31 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes 2026/06/09 04:46:31 DEBUG : drime root 'rclone-test-kuwutil9meqo': File to upload is small (11 bytes), uploading instead of streaming 2026/06/09 04:46:32 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "The file failed to upload." 2026/06/09 04:46:32 DEBUG : file1: Remove: 2026/06/09 04:46:32 DEBUG : Added virtual directory entry vDel: "file1" 2026/06/09 04:46:32 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 "The file failed to upload." Test: TestWriteFileHandleWriteAt 2026/06/09 04:46:32 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/5 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/5 2026/06/09 04:46:36 DEBUG : forgetting directory cache fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/5 fstest.go:301: Flushing the directory cache 2026/06/09 04:46:44 DEBUG : forgetting directory cache fstest.go:298: Sleeping for 8s for list eventual consistency: 4/5 2026/06/09 04:46:53 DEBUG : forgetting directory cache fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 16s for list eventual consistency: 5/5 2026/06/09 04:47:03 DEBUG : forgetting directory cache 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/06/09 04:47:12 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:47:12 DEBUG : Looking for writers 2026/06/09 04:47:12 DEBUG : >WaitForWriters: 2026/06/09 04:47:13 DEBUG : forgetting directory cache --- FAIL: TestWriteFileHandleWriteAt (46.27s) === RUN TestWriteFileHandleFlush run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:47:15 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:47:15 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:47:17 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:47:17 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:47:17 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:47:17 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:47:17 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2026/06/09 04:47:17 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:47:17 DEBUG : drime root 'rclone-test-kuwutil9meqo': File to upload is small (5 bytes), uploading instead of streaming 2026/06/09 04:47:19 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "The file failed to upload." 2026/06/09 04:47:19 DEBUG : file1: Remove: 2026/06/09 04:47:19 DEBUG : Added virtual directory entry vDel: "file1" 2026/06/09 04:47:19 DEBUG : file1: >Remove: err= 2026/06/09 04:47:19 ERROR : file1: WriteFileHandle.Flush error: failed to upload file: Error "The file failed to upload." 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 "The file failed to upload." Test: TestWriteFileHandleFlush 2026/06/09 04:47:19 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/06/09 04:47:19 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:47:19 DEBUG : Looking for writers 2026/06/09 04:47:19 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleFlush (5.43s) === RUN TestWriteFileModTimeWithOpenWriters run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:47:21 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:47:21 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:47:22 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:47:22 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:47:22 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:47:22 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:47:22 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:47:22 DEBUG : drime root 'rclone-test-kuwutil9meqo': File to upload is small (2 bytes), uploading instead of streaming 2026/06/09 04:47:23 DEBUG : forgetting directory cache 2026/06/09 04:47:23 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "The file failed to upload." 2026/06/09 04:47:23 DEBUG : file1: Remove: 2026/06/09 04:47:23 DEBUG : Added virtual directory entry vDel: "file1" 2026/06/09 04:47:23 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 "The file failed to upload." Test: TestWriteFileModTimeWithOpenWriters 2026/06/09 04:47:23 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:47:23 DEBUG : Looking for writers 2026/06/09 04:47:23 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileModTimeWithOpenWriters (3.87s) === RUN TestFileReadAtNonZeroLength run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:47:25 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:47:25 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/06/09 04:47:25 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/06/09 04:47:25 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:47:25 DEBUG : file1: >Open: fd=file1 (w), err= 2026/06/09 04:47:25 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/06/09 04:47:25 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/06/09 04:47:25 DEBUG : drime root 'rclone-test-kuwutil9meqo': File to upload is small (100 bytes), uploading instead of streaming 2026/06/09 04:47:27 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: Error "The file failed to upload." 2026/06/09 04:47:27 DEBUG : file1: Remove: 2026/06/09 04:47:27 DEBUG : Added virtual directory entry vDel: "file1" 2026/06/09 04:47:27 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 "The file failed to upload." Test: TestFileReadAtNonZeroLength 2026/06/09 04:47:27 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/06/09 04:47:27 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/06/09 04:47:27 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:47:27 DEBUG : Looking for writers 2026/06/09 04:47:27 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtNonZeroLength (4.08s) === RUN TestZipManyFiles run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:47:29 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "flat/f000.txt" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:47:32 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:47:32 DEBUG : Looking for writers 2026/06/09 04:47:32 DEBUG : >WaitForWriters: 2026/06/09 04:47:34 DEBUG : forgetting directory cache --- FAIL: TestZipManyFiles (7.97s) === RUN TestZipManySubDirs run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:47:37 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote 2026/06/09 04:47:41 DEBUG : forgetting directory cache run.go:303: Failed to put "a/top.txt" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:47:42 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:47:42 DEBUG : Looking for writers 2026/06/09 04:47:42 DEBUG : >WaitForWriters: --- FAIL: TestZipManySubDirs (11.32s) === RUN TestZipLargeFiles run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:47:48 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "bigdir/big.bin" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:47:52 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:47:52 DEBUG : Looking for writers 2026/06/09 04:47:52 DEBUG : >WaitForWriters: 2026/06/09 04:47:52 DEBUG : forgetting directory cache --- FAIL: TestZipLargeFiles (6.91s) === RUN TestZipDirsInRoot run.go:198: Remote "drime root 'rclone-test-kuwutil9meqo'", Local "Local file system at /tmp/rclone3330174522", Modify Window "876000h0m0s" 2026/06/09 04:47:55 INFO : drime root 'rclone-test-kuwutil9meqo': poll-interval is not supported by this remote run.go:303: Failed to put "dir1/a.txt" to "drime root 'rclone-test-kuwutil9meqo'": failed to upload file: Error "The file failed to upload." 2026/06/09 04:47:58 DEBUG : WaitForWriters: timeout=30s 2026/06/09 04:47:58 DEBUG : Looking for writers 2026/06/09 04:47:58 DEBUG : >WaitForWriters: 2026/06/09 04:48:01 DEBUG : forgetting directory cache --- FAIL: TestZipDirsInRoot (8.53s) FAIL 2026/06/09 04:48:03 DEBUG : drime root 'rclone-test-kuwutil9meqo': Purge remote "./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestCaseSensitivity|TestDirCreate|TestDirFileOpen|TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMetadataExtension|TestDirMethods|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleMethodsWrite|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestRWFileHandleWriteAt|TestRWFileModTimeWithOpenWriters|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|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 14m19.221229503s (try 3/5): exit status 1: Failed [TestDirHandleMethods TestDirHandleReaddir TestDirHandleReaddirnames TestDirMethods TestDirForgetAll TestDirForgetPath TestDirWalk TestDirSetModTime TestDirStat TestDirReadDirAll TestDirOpen TestDirCreate TestDirMkdir TestDirMkdirSub 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 TestRWFileHandleSizeTruncateExisting TestRWFileHandleSizeCreateExisting TestRWFileModTimeWithOpenWriters TestCaseSensitivity TestUnicodeNormalization TestVFSStat TestVFSStatParent TestVFSOpenFile TestVFSRename TestWriteFileHandleMethods TestWriteFileHandleWriteAt TestWriteFileHandleFlush TestWriteFileModTimeWithOpenWriters TestFileReadAtNonZeroLength TestZipManyFiles TestZipManySubDirs TestZipLargeFiles TestZipDirsInRoot]