"./vfs.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^(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=true,write=true)$'" - Starting (try 5/5)
2025/07/26 04:02:40 DEBUG : Creating backend with remote "TestBox:rclone-test-poduloz0rahu"
2025/07/26 04:02:40 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/26 04:02:41 DEBUG : box root 'rclone-test-poduloz0rahu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/07/26 04:02:41 DEBUG : Creating backend with remote "/tmp/rclone2872415881"
=== RUN   TestFileSetModTime
=== RUN   TestFileSetModTime/cache=off,open=true,write=true
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:02:42 DEBUG : box root 'rclone-test-poduloz0rahu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/07/26 04:02:51 DEBUG : Can set mod time: true
2025/07/26 04:02:51 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2025/07/26 04:02:51 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err=<nil>
2025/07/26 04:02:51 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2025/07/26 04:02:51 DEBUG : box root 'rclone-test-poduloz0rahu': File to upload is small (5 bytes), uploading instead of streaming
2025/07/26 04:02:52 DEBUG : dir/file1: sha1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK
2025/07/26 04:02:52 DEBUG : dir/file1: Size and sha1 of src and dst objects identical
2025/07/26 04:02:53 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK
2025/07/26 04:02:53 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2025/07/26 04:02:53 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:02:53 DEBUG : dir: Looking for writers
2025/07/26 04:02:53 DEBUG : file1: reading active writers
2025/07/26 04:02:53 DEBUG : Looking for writers
2025/07/26 04:02:53 DEBUG : dir: reading active writers
2025/07/26 04:02:53 DEBUG : >WaitForWriters: 
2025/07/26 04:02:54 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:02:54 DEBUG : dir: Looking for writers
2025/07/26 04:02:54 DEBUG : file1: reading active writers
2025/07/26 04:02:54 DEBUG : Looking for writers
2025/07/26 04:02:54 DEBUG : dir: reading active writers
2025/07/26 04:02:54 DEBUG : >WaitForWriters: 
2025/07/26 04:02:57 ERROR : dir: error listing: couldn't list files: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"folder","message":"Invalid value '332935578736'. 'folder' with value '332935578736' 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 '332935578736'. 'folder' with value '332935578736' not found"}]})
=== RUN   TestFileSetModTime/cache=full,open=false,write=false
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:02:57 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:02:57 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:57 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:57 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:57 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:02:57 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:57 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:57 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:02:57 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:57 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:02:58 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:02:58 DEBUG : Looking for writers
2025/07/26 04:02:58 DEBUG : >WaitForWriters: 
2025/07/26 04:02:58 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
=== RUN   TestFileSetModTime/cache=full,open=true,write=false
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:02:59 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:02:59 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:59 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:59 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:59 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:02:59 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:59 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:59 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:02:59 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:02:59 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:02:59 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:02:59 DEBUG : Looking for writers
2025/07/26 04:02:59 DEBUG : >WaitForWriters: 
2025/07/26 04:02:59 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
=== RUN   TestFileSetModTime/cache=full,open=true,write=true
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:00 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:00 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:00 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:00 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:00 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:00 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:00 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:00 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:00 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:00 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:00 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:00 DEBUG : Looking for writers
2025/07/26 04:03:00 DEBUG : >WaitForWriters: 
2025/07/26 04:03:00 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
--- FAIL: TestFileSetModTime (19.58s)
    --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (16.17s)
    --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (1.12s)
    --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (1.14s)
    --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (1.15s)
=== RUN   TestFileOpenRead
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:01 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:01 DEBUG : Looking for writers
2025/07/26 04:03:01 DEBUG : >WaitForWriters: 
--- FAIL: TestFileOpenRead (1.12s)
=== RUN   TestFileOpenWrite
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:02 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:02 DEBUG : Looking for writers
2025/07/26 04:03:02 DEBUG : >WaitForWriters: 
--- FAIL: TestFileOpenWrite (1.15s)
=== RUN   TestFileRemove
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:03 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:03 DEBUG : Looking for writers
2025/07/26 04:03:03 DEBUG : >WaitForWriters: 
--- FAIL: TestFileRemove (1.10s)
=== RUN   TestFileRemoveAll
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:05 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:05 DEBUG : Looking for writers
2025/07/26 04:03:05 DEBUG : >WaitForWriters: 
--- FAIL: TestFileRemoveAll (1.11s)
=== RUN   TestFileOpen
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:06 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:06 DEBUG : Looking for writers
2025/07/26 04:03:06 DEBUG : >WaitForWriters: 
--- FAIL: TestFileOpen (1.10s)
=== RUN   TestFileRename
=== RUN   TestFileRename/off,forceCache=false
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:07 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:07 DEBUG : Looking for writers
2025/07/26 04:03:07 DEBUG : >WaitForWriters: 
=== RUN   TestFileRename/minimal,forceCache=false
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:08 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:08 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:08 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:08 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:08 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:08 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:08 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:08 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:08 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:08 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:08 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:08 DEBUG : Looking for writers
2025/07/26 04:03:08 DEBUG : >WaitForWriters: 
2025/07/26 04:03:08 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
=== RUN   TestFileRename/minimal,forceCache=true
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:09 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:09 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:09 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:09 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:09 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:09 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:09 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:09 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:09 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:09 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:09 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:09 DEBUG : Looking for writers
2025/07/26 04:03:09 DEBUG : >WaitForWriters: 
2025/07/26 04:03:09 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
=== RUN   TestFileRename/writes,forceCache=false
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:10 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:10 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:10 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:10 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:10 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:10 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:10 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:10 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:10 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:10 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:10 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:10 DEBUG : Looking for writers
2025/07/26 04:03:10 DEBUG : >WaitForWriters: 
2025/07/26 04:03:10 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
=== RUN   TestFileRename/writes,forceCache=true
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:11 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:11 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:11 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:11 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:11 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:11 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:11 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:11 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:11 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:11 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:11 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:11 DEBUG : Looking for writers
2025/07/26 04:03:11 DEBUG : >WaitForWriters: 
2025/07/26 04:03:11 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
=== RUN   TestFileRename/full,forceCache=false
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:12 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:12 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:12 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:12 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:12 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:12 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:12 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:12 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:12 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:12 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:12 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:12 DEBUG : Looking for writers
2025/07/26 04:03:12 DEBUG : >WaitForWriters: 
2025/07/26 04:03:12 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
--- FAIL: TestFileRename (6.81s)
    --- FAIL: TestFileRename/off,forceCache=false (1.11s)
    --- FAIL: TestFileRename/minimal,forceCache=false (1.13s)
    --- FAIL: TestFileRename/minimal,forceCache=true (1.13s)
    --- FAIL: TestFileRename/writes,forceCache=false (1.16s)
    --- FAIL: TestFileRename/writes,forceCache=true (1.13s)
    --- FAIL: TestFileRename/full,forceCache=false (1.16s)
=== RUN   TestReadFileHandleMethods
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:14 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:14 DEBUG : Looking for writers
2025/07/26 04:03:14 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleMethods (1.10s)
=== RUN   TestReadFileHandleSeek
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:15 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:15 DEBUG : Looking for writers
2025/07/26 04:03:15 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleSeek (1.13s)
=== RUN   TestReadFileHandleReadAt
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:16 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:16 DEBUG : Looking for writers
2025/07/26 04:03:16 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleReadAt (1.13s)
=== RUN   TestReadFileHandleFlush
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:17 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:17 DEBUG : Looking for writers
2025/07/26 04:03:17 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleFlush (1.15s)
=== RUN   TestReadFileHandleRelease
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file1" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:18 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:18 DEBUG : Looking for writers
2025/07/26 04:03:18 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleRelease (1.12s)
=== RUN   TestRWFileHandleMethodsRead
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:19 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:19 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:19 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:19 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:19 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:19 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:19 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:19 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:19 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:19 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:19 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:19 DEBUG : Looking for writers
2025/07/26 04:03:19 DEBUG : >WaitForWriters: 
2025/07/26 04:03:19 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleMethodsRead (1.15s)
=== RUN   TestRWFileHandleSeek
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:20 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:20 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:20 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:20 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:20 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:20 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:20 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:20 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:20 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:20 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:20 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:20 DEBUG : Looking for writers
2025/07/26 04:03:20 DEBUG : >WaitForWriters: 
2025/07/26 04:03:20 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleSeek (1.13s)
=== RUN   TestRWFileHandleReadAt
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:21 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:21 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:21 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:21 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:21 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:21 DEBUG : Looking for writers
2025/07/26 04:03:21 DEBUG : >WaitForWriters: 
2025/07/26 04:03:21 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleReadAt (1.11s)
=== RUN   TestRWFileHandleFlushRead
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:22 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:22 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:22 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:22 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:22 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:22 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:23 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:23 DEBUG : Looking for writers
2025/07/26 04:03:23 DEBUG : >WaitForWriters: 
2025/07/26 04:03:23 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleFlushRead (1.11s)
=== RUN   TestRWFileHandleReleaseRead
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
2025/07/26 04:03:23 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: root is "/home/rclone/.cache/rclone"
2025/07/26 04:03:23 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:23 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/07/26 04:03:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestBox/rclone-test-poduloz0rahu"
2025/07/26 04:03:23 INFO  : box root 'rclone-test-poduloz0rahu': 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-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:24 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:24 DEBUG : Looking for writers
2025/07/26 04:03:24 DEBUG : >WaitForWriters: 
2025/07/26 04:03:24 DEBUG : box root 'rclone-test-poduloz0rahu': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleReleaseRead (1.15s)
=== RUN   TestVFSStat
    run.go:180: Remote "box root 'rclone-test-poduloz0rahu'", Local "Local file system at /tmp/rclone2872415881", Modify Window "1s"
    run.go:285: Failed to put "dir/file2" to "box root 'rclone-test-poduloz0rahu'": pre-upload check: Error "not_found" (404): Not Found ({"errors":[{"reason":"invalid_parameter","name":"item","message":"Invalid value 'd_332935578736'. 'item' with value 'd_332935578736' not found"}]})
2025/07/26 04:03:26 DEBUG : WaitForWriters: timeout=30s
2025/07/26 04:03:26 DEBUG : Looking for writers
2025/07/26 04:03:26 DEBUG : >WaitForWriters: 
--- FAIL: TestVFSStat (3.81s)
FAIL
2025/07/26 04:03:28 DEBUG : box root 'rclone-test-poduloz0rahu': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^(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=true,write=true)$'" - Finished ERROR in 48.973614691s (try 5/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]