"./vfs.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^(TestDirMetadataExtension|TestDirRename|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestVFSStat)$|^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 4/5) 2025/07/26 04:00:56 DEBUG : Creating backend with remote "TestBox:rclone-test-wenisew5bele" 2025/07/26 04:00:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/26 04:00:57 DEBUG : box root 'rclone-test-wenisew5bele': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/07/26 04:00:57 DEBUG : Creating backend with remote "/tmp/rclone1593967635" === RUN TestDirRename run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:00:58 DEBUG : box root 'rclone-test-wenisew5bele': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/07/26 04:01:05 ERROR : dir/not found: Dir.Rename error: file does not exist 2025/07/26 04:01:05 DEBUG : dir: Updating dir with dir2 0xc0009469c0 2025/07/26 04:01:05 DEBUG : dir: forgetting directory cache 2025/07/26 04:01:05 DEBUG : Added virtual directory entry vDel: "dir" 2025/07/26 04:01:05 DEBUG : Added virtual directory entry vAddDir: "dir2" 2025/07/26 04:01:08 INFO : dir2/file1: Moved (server-side) to: file2 2025/07/26 04:01:08 DEBUG : file2: Updating file with file2 0xc00094a300 2025/07/26 04:01:08 DEBUG : dir2: Added virtual directory entry vDel: "file1" 2025/07/26 04:01:08 DEBUG : Added virtual directory entry vAddFile: "file2" 2025/07/26 04:01:11 INFO : dir2/file3: Deleted 2025/07/26 04:01:12 INFO : file2: Moved (server-side) to: dir2/file3 2025/07/26 04:01:12 DEBUG : dir2/file3: Updating file with dir2/file3 0xc00094a300 2025/07/26 04:01:12 DEBUG : Added virtual directory entry vDel: "file2" 2025/07/26 04:01:12 DEBUG : dir2: Added virtual directory entry vAddFile: "file3" 2025/07/26 04:01:14 DEBUG : Added virtual directory entry vAddDir: "empty directory" 2025/07/26 04:01:15 DEBUG : empty directory: Updating dir with renamed empty directory 0xc000666000 2025/07/26 04:01:15 DEBUG : empty directory: forgetting directory cache 2025/07/26 04:01:15 DEBUG : Added virtual directory entry vDel: "empty directory" 2025/07/26 04:01:15 DEBUG : Added virtual directory entry vAddDir: "renamed empty directory" 2025/07/26 04:01:15 DEBUG : dir2: Renaming to "dir3" 2025/07/26 04:01:15 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:01:15 DEBUG : dir3: Looking for writers 2025/07/26 04:01:15 DEBUG : file3: reading active writers 2025/07/26 04:01:15 DEBUG : renamed empty directory: Looking for writers 2025/07/26 04:01:15 DEBUG : Looking for writers 2025/07/26 04:01:15 DEBUG : dir3: reading active writers 2025/07/26 04:01:15 DEBUG : renamed empty directory: reading active writers 2025/07/26 04:01:15 DEBUG : >WaitForWriters: --- PASS: TestDirRename (22.30s) === RUN TestDirMetadataExtension run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:01:23 DEBUG : dir: Added virtual directory entry vAddFile: "file1.metadata" 2025/07/26 04:01:23 DEBUG : dir/file1.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/07/26 04:01:23 DEBUG : dir/file1.metadata: Open: flags=O_RDONLY 2025/07/26 04:01:23 DEBUG : dir/file1.metadata: >Open: fd=dir/file1.metadata (r), err= 2025/07/26 04:01:23 DEBUG : dir/file1.metadata: >OpenFile: fd=dir/file1.metadata (r), err= 2025/07/26 04:01:23 DEBUG : dir/file1.metadata: ChunkedReader.openRange at 0 length 134217728 2025/07/26 04:01:23 DEBUG : dir/file1.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/07/26 04:01:23 DEBUG : Added virtual directory entry vAddFile: "dir.metadata" 2025/07/26 04:01:23 DEBUG : dir.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/07/26 04:01:23 DEBUG : dir.metadata: Open: flags=O_RDONLY 2025/07/26 04:01:23 DEBUG : dir.metadata: >Open: fd=dir.metadata (r), err= 2025/07/26 04:01:23 DEBUG : dir.metadata: >OpenFile: fd=dir.metadata (r), err= 2025/07/26 04:01:23 DEBUG : dir.metadata: ChunkedReader.openRange at 0 length 134217728 2025/07/26 04:01:23 DEBUG : dir.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/07/26 04:01:23 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:01:23 DEBUG : dir: Looking for writers 2025/07/26 04:01:23 DEBUG : file1: reading active writers 2025/07/26 04:01:23 DEBUG : file1.metadata: reading active writers 2025/07/26 04:01:23 DEBUG : Looking for writers 2025/07/26 04:01:23 DEBUG : dir.metadata: reading active writers 2025/07/26 04:01:23 DEBUG : dir: reading active writers 2025/07/26 04:01:23 DEBUG : >WaitForWriters: --- PASS: TestDirMetadataExtension (7.46s) === RUN TestFileMethods run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:01:31 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:01:31 DEBUG : dir: Looking for writers 2025/07/26 04:01:31 DEBUG : file1: reading active writers 2025/07/26 04:01:31 DEBUG : Looking for writers 2025/07/26 04:01:31 DEBUG : dir: reading active writers 2025/07/26 04:01:31 DEBUG : >WaitForWriters: --- PASS: TestFileMethods (7.38s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:01:43 DEBUG : Can set mod time: true 2025/07/26 04:01:43 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/07/26 04:01:44 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:01:44 DEBUG : dir: Looking for writers 2025/07/26 04:01:44 DEBUG : file1: reading active writers 2025/07/26 04:01:44 DEBUG : Looking for writers 2025/07/26 04:01:44 DEBUG : dir: reading active writers 2025/07/26 04:01:44 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:01:51 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/07/26 04:01:51 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/07/26 04:01:51 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/07/26 04:01:51 DEBUG : box root 'rclone-test-wenisew5bele': File to upload is small (0 bytes), uploading instead of streaming 2025/07/26 04:01:53 DEBUG : dir/file1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/07/26 04:01:53 DEBUG : dir/file1: Size and sha1 of src and dst objects identical 2025/07/26 04:01:53 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/07/26 04:01:53 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/07/26 04:01:53 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:01:53 DEBUG : dir: Looking for writers 2025/07/26 04:01:53 DEBUG : file1: reading active writers 2025/07/26 04:01:53 DEBUG : Looking for writers 2025/07/26 04:01:53 DEBUG : dir: reading active writers 2025/07/26 04:01:53 DEBUG : >WaitForWriters: 2025/07/26 04:01:54 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:01:54 DEBUG : dir: Looking for writers 2025/07/26 04:01:54 DEBUG : file1: reading active writers 2025/07/26 04:01:54 DEBUG : Looking for writers 2025/07/26 04:01:54 DEBUG : dir: reading active writers 2025/07/26 04:01:54 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:02 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/07/26 04:02:02 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/07/26 04:02:02 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/07/26 04:02:02 DEBUG : box root 'rclone-test-wenisew5bele': File to upload is small (5 bytes), uploading instead of streaming 2025/07/26 04:02:03 DEBUG : dir/file1: sha1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2025/07/26 04:02:03 DEBUG : dir/file1: Size and sha1 of src and dst objects identical 2025/07/26 04:02:04 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/07/26 04:02:04 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/07/26 04:02:04 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:04 DEBUG : dir: Looking for writers 2025/07/26 04:02:04 DEBUG : file1: reading active writers 2025/07/26 04:02:04 DEBUG : Looking for writers 2025/07/26 04:02:04 DEBUG : dir: reading active writers 2025/07/26 04:02:04 DEBUG : >WaitForWriters: 2025/07/26 04:02:05 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:05 DEBUG : dir: Looking for writers 2025/07/26 04:02:05 DEBUG : file1: reading active writers 2025/07/26 04:02:05 DEBUG : Looking for writers 2025/07/26 04:02:05 DEBUG : dir: reading active writers 2025/07/26 04:02:05 DEBUG : >WaitForWriters: 2025/07/26 04:02:08 ERROR : dir: error listing: couldn't list files: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"folder","message":"Invalid value '332935048587'. 'folder' with value '332935048587' not found"}]}) fstest.go:279: Error listing: couldn't list files: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"folder","message":"Invalid value '332935048587'. 'folder' with value '332935048587' not found"}]}) === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:08 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:08 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:08 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:08 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:08 DEBUG : Config file has changed externally - reloading 2025/07/26 04:02:08 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:08 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:08 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:08 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:08 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:08 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:09 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:09 DEBUG : Looking for writers 2025/07/26 04:02:09 DEBUG : >WaitForWriters: 2025/07/26 04:02:09 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:09 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:09 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:09 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:09 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:09 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:09 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:09 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:09 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:09 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:09 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:10 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:10 DEBUG : Looking for writers 2025/07/26 04:02:10 DEBUG : >WaitForWriters: 2025/07/26 04:02:10 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:11 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:11 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:11 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:11 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:11 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:11 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:11 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:11 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:11 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:11 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:11 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:11 DEBUG : Looking for writers 2025/07/26 04:02:11 DEBUG : >WaitForWriters: 2025/07/26 04:02:11 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting --- FAIL: TestFileSetModTime (37.25s) --- PASS: TestFileSetModTime/cache=off,open=false,write=false (12.68s) --- PASS: TestFileSetModTime/cache=off,open=true,write=false (10.37s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (10.80s) --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (1.12s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (1.13s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (1.15s) === RUN TestFileOpenRead run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:12 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:12 DEBUG : Looking for writers 2025/07/26 04:02:12 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenRead (1.17s) === RUN TestFileOpenWrite run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:13 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:13 DEBUG : Looking for writers 2025/07/26 04:02:13 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenWrite (1.15s) === RUN TestFileRemove run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:14 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:14 DEBUG : Looking for writers 2025/07/26 04:02:14 DEBUG : >WaitForWriters: --- FAIL: TestFileRemove (1.14s) === RUN TestFileRemoveAll run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:15 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:15 DEBUG : Looking for writers 2025/07/26 04:02:15 DEBUG : >WaitForWriters: --- FAIL: TestFileRemoveAll (1.14s) === RUN TestFileOpen run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:17 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:17 DEBUG : Looking for writers 2025/07/26 04:02:17 DEBUG : >WaitForWriters: --- FAIL: TestFileOpen (1.15s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:18 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:18 DEBUG : Looking for writers 2025/07/26 04:02:18 DEBUG : >WaitForWriters: === RUN TestFileRename/minimal,forceCache=false run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:19 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:19 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:19 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:19 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:19 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:19 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:19 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:19 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:19 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:19 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:19 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:19 DEBUG : Looking for writers 2025/07/26 04:02:19 DEBUG : >WaitForWriters: 2025/07/26 04:02:19 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting === RUN TestFileRename/minimal,forceCache=true run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:20 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:20 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:20 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:20 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:20 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:20 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:20 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:20 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:20 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:20 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:20 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:20 DEBUG : Looking for writers 2025/07/26 04:02:20 DEBUG : >WaitForWriters: 2025/07/26 04:02:20 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=false run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:21 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:21 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:21 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:21 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:21 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:21 DEBUG : Looking for writers 2025/07/26 04:02:21 DEBUG : >WaitForWriters: 2025/07/26 04:02:21 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=true run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:22 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:22 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:22 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:22 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:22 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:22 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:22 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:22 DEBUG : Looking for writers 2025/07/26 04:02:22 DEBUG : >WaitForWriters: 2025/07/26 04:02:22 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting === RUN TestFileRename/full,forceCache=false run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:23 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:23 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:23 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:23 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:23 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:23 DEBUG : Looking for writers 2025/07/26 04:02:23 DEBUG : >WaitForWriters: 2025/07/26 04:02:23 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting --- FAIL: TestFileRename (6.73s) --- FAIL: TestFileRename/off,forceCache=false (1.11s) --- FAIL: TestFileRename/minimal,forceCache=false (1.12s) --- FAIL: TestFileRename/minimal,forceCache=true (1.12s) --- FAIL: TestFileRename/writes,forceCache=false (1.12s) --- FAIL: TestFileRename/writes,forceCache=true (1.13s) --- FAIL: TestFileRename/full,forceCache=false (1.12s) === RUN TestReadFileHandleMethods run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:24 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:24 DEBUG : Looking for writers 2025/07/26 04:02:24 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (1.13s) === RUN TestReadFileHandleSeek run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:26 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:26 DEBUG : Looking for writers 2025/07/26 04:02:26 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleSeek (1.12s) === RUN TestReadFileHandleReadAt run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:27 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:27 DEBUG : Looking for writers 2025/07/26 04:02:27 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (1.11s) === RUN TestReadFileHandleFlush run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:28 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:28 DEBUG : Looking for writers 2025/07/26 04:02:28 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleFlush (1.12s) === RUN TestReadFileHandleRelease run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:29 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:29 DEBUG : Looking for writers 2025/07/26 04:02:29 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (1.10s) === RUN TestRWFileHandleMethodsRead run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:30 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:30 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:30 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:30 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:30 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:30 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:30 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:30 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:30 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:30 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:30 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:30 DEBUG : Looking for writers 2025/07/26 04:02:30 DEBUG : >WaitForWriters: 2025/07/26 04:02:30 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (1.13s) === RUN TestRWFileHandleSeek run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:31 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:31 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:31 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:31 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:31 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:31 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:31 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:31 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:31 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:31 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:31 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:31 DEBUG : Looking for writers 2025/07/26 04:02:31 DEBUG : >WaitForWriters: 2025/07/26 04:02:31 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (1.13s) === RUN TestRWFileHandleReadAt run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:32 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:32 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:32 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:32 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:32 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:32 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:32 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:32 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:32 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:32 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:32 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:32 DEBUG : Looking for writers 2025/07/26 04:02:32 DEBUG : >WaitForWriters: 2025/07/26 04:02:32 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (1.13s) === RUN TestRWFileHandleFlushRead run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:33 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:33 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:33 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:33 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:33 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:33 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:33 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:33 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:33 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:33 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:33 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:33 DEBUG : Looking for writers 2025/07/26 04:02:33 DEBUG : >WaitForWriters: 2025/07/26 04:02:33 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleFlushRead (1.12s) === RUN TestRWFileHandleReleaseRead run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" 2025/07/26 04:02:34 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/26 04:02:34 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:34 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/26 04:02:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-wenisew5bele" 2025/07/26 04:02:34 INFO : box root 'rclone-test-wenisew5bele': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:35 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:35 DEBUG : Looking for writers 2025/07/26 04:02:35 DEBUG : >WaitForWriters: 2025/07/26 04:02:35 DEBUG : box root 'rclone-test-wenisew5bele': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (1.15s) === RUN TestVFSStat run.go:180: Remote "box root 'rclone-test-wenisew5bele'", Local "Local file system at /tmp/rclone1593967635", Modify Window "1s" run.go:285: Failed to put "dir/file2" to "box root 'rclone-test-wenisew5bele'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935048587'. 'item' with value 'd_332935048587' not found"}]}) 2025/07/26 04:02:37 DEBUG : WaitForWriters: timeout=30s 2025/07/26 04:02:37 DEBUG : Looking for writers 2025/07/26 04:02:37 DEBUG : >WaitForWriters: --- FAIL: TestVFSStat (4.04s) FAIL 2025/07/26 04:02:39 DEBUG : box root 'rclone-test-wenisew5bele': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^(TestDirMetadataExtension|TestDirRename|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestVFSStat)$|^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 1m44.044760982s (try 4/5): exit status 1: Failed [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 TestVFSStat]