"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirCreate|TestDirFileOpen|TestDirMetadataExtension|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleWriteAt|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestWriteFileHandleFlush|TestZipDirsInRoot|TestZipLargeFiles|TestZipManyFiles|TestZipManySubDirs)$|^TestFileRename$/^(minimal,forceCache=false|minimal,forceCache=true|off,forceCache=false|writes,forceCache=false|writes,forceCache=true)$|^TestFileSetModTime$/^cache=off,open=false,write=false$'" - Starting (try 5/5) 2026/04/18 06:50:00 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-cinibob8wixa" 2026/04/18 06:50:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/04/18 06:50:00 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': Using cached web token 2026/04/18 06:50:01 DEBUG : Creating backend with remote "/tmp/rclone3066160285" === RUN TestDirCreate run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:50:01 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:50:01 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:50:07 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2026/04/18 06:50:07 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/04/18 06:50:07 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2026/04/18 06:50:07 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/04/18 06:50:07 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': File to upload is small (5 bytes), uploading instead of streaming 2026/04/18 06:50:09 DEBUG : dir/potato: size = 5 OK 2026/04/18 06:50:09 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/04/18 06:50:09 DEBUG : dir/potato: Size of src and dst objects identical 2026/04/18 06:50:09 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/04/18 06:50:09 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:50:09 DEBUG : dir: Looking for writers 2026/04/18 06:50:09 DEBUG : file1: reading active writers 2026/04/18 06:50:09 DEBUG : potato: reading active writers 2026/04/18 06:50:09 DEBUG : Looking for writers 2026/04/18 06:50:09 DEBUG : dir: reading active writers 2026/04/18 06:50:09 DEBUG : >WaitForWriters: --- PASS: TestDirCreate (12.79s) === RUN TestDirFileOpen run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:50:14 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:50:14 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:50:20 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2026/04/18 06:50:20 DEBUG : dir/sub/file0: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2026/04/18 06:50:20 DEBUG : dir/sub/file0: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2026/04/18 06:50:20 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2026/04/18 06:50:20 DEBUG : dir/sub/file0: >Open: fd=dir/sub/file0 (w), err= 2026/04/18 06:50:20 DEBUG : dir/sub/file0: >OpenFile: fd=dir/sub/file0 (w), err= 2026/04/18 06:50:20 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2026/04/18 06:50:20 DEBUG : dir/sub/file2: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2026/04/18 06:50:20 DEBUG : dir/sub/file2: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2026/04/18 06:50:20 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2026/04/18 06:50:20 DEBUG : dir/sub/file2: >Open: fd=dir/sub/file2 (w), err= 2026/04/18 06:50:20 DEBUG : dir/sub/file2: >OpenFile: fd=dir/sub/file2 (w), err= 2026/04/18 06:50:20 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2026/04/18 06:50:20 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': File to upload is small (12 bytes), uploading instead of streaming 2026/04/18 06:50:22 DEBUG : dir/sub/file2: size = 12 OK 2026/04/18 06:50:22 DEBUG : dir/sub/file2: Size of src and dst objects identical 2026/04/18 06:50:22 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2026/04/18 06:50:22 DEBUG : forgetting directory cache 2026/04/18 06:50:22 DEBUG : dir: forgetting directory cache 2026/04/18 06:50:22 DEBUG : dir/sub: forgetting directory cache 2026/04/18 06:50:22 DEBUG : dir/sub: Removed virtual directory entry vAddFile: "file2" 2026/04/18 06:50:22 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2026/04/18 06:50:22 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': File to upload is small (5 bytes), uploading instead of streaming 2026/04/18 06:50:24 DEBUG : dir/sub/file0: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:50:24 DEBUG : dir/sub/file0: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:50:25 DEBUG : dir/sub/file0: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:50:25 DEBUG : dir/sub/file0: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:50:26 DEBUG : dir/sub/file0: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:50:28 DEBUG : dir/sub/file0: size = 5 OK 2026/04/18 06:50:28 DEBUG : dir/sub/file0: Size of src and dst objects identical 2026/04/18 06:50:28 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2026/04/18 06:50:28 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:50:28 DEBUG : dir/sub: Looking for writers 2026/04/18 06:50:28 DEBUG : file0: reading active writers 2026/04/18 06:50:28 DEBUG : file2: reading active writers 2026/04/18 06:50:28 DEBUG : dir: Looking for writers 2026/04/18 06:50:28 DEBUG : file1: reading active writers 2026/04/18 06:50:28 DEBUG : sub: reading active writers 2026/04/18 06:50:28 DEBUG : Looking for writers 2026/04/18 06:50:28 DEBUG : dir: reading active writers 2026/04/18 06:50:28 DEBUG : >WaitForWriters: run.go:130: removing dir "dir/sub" failed - try 1/3: directory not empty run.go:130: removing dir "dir/sub" failed - try 2/3: directory not empty run.go:130: removing dir "dir/sub" failed - try 3/3: directory not empty run.go:133: removing dir "dir/sub" failed: directory not empty run.go:130: removing dir "dir" failed - try 1/3: directory not empty run.go:130: removing dir "dir" failed - try 2/3: directory not empty run.go:130: removing dir "dir" failed - try 3/3: directory not empty run.go:133: removing dir "dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Not equal: expected: []string{} actual : []string{"dir", "dir/sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "dir", + (string) (len=7) "dir/sub" } Test: TestDirFileOpen Messages: directories --- FAIL: TestDirFileOpen (35.16s) === RUN TestDirMetadataExtension run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:50:49 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:50:49 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:50:52 DEBUG : dir/file1: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:50:52 DEBUG : dir/file1: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:50:52 DEBUG : dir/file1: Trying to read object after upload: try again in 400ms (3/10) dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:704 Error: Not equal: expected: []string{"file1,14,false"} actual : []string{"file1,14,false", "sub,0,true"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=1) { - (string) (len=14) "file1,14,false" +([]string) (len=2) { + (string) (len=14) "file1,14,false", + (string) (len=10) "sub,0,true" } Test: TestDirMetadataExtension 2026/04/18 06:50:54 DEBUG : dir: Added virtual directory entry vAddFile: "file1.metadata" 2026/04/18 06:50:54 DEBUG : dir/file1.metadata: Set virtual modtime to 2026-04-18 06:50:50 +0000 UTC dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:733 Error: Not equal: expected: []string{"file1,14,false", "file1.metadata,2,false"} actual : []string{"file1,14,false", "file1.metadata,2,false", "sub,0,true"} Diff: --- Expected +++ Actual @@ -1,4 +1,5 @@ -([]string) (len=2) { +([]string) (len=3) { (string) (len=14) "file1,14,false", - (string) (len=22) "file1.metadata,2,false" + (string) (len=22) "file1.metadata,2,false", + (string) (len=10) "sub,0,true" } Test: TestDirMetadataExtension 2026/04/18 06:50:54 DEBUG : dir/file1.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2026/04/18 06:50:54 DEBUG : dir/file1.metadata: Open: flags=O_RDONLY 2026/04/18 06:50:54 DEBUG : dir/file1.metadata: >Open: fd=dir/file1.metadata (r), err= 2026/04/18 06:50:54 DEBUG : dir/file1.metadata: >OpenFile: fd=dir/file1.metadata (r), err= 2026/04/18 06:50:54 DEBUG : dir/file1.metadata: ChunkedReader.openRange at 0 length 134217728 2026/04/18 06:50:54 DEBUG : dir/file1.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2026/04/18 06:50:54 DEBUG : Added virtual directory entry vAddFile: "dir.metadata" 2026/04/18 06:50:54 DEBUG : dir.metadata: Set virtual modtime to 2026-04-18 06:50:14 +0000 UTC 2026/04/18 06:50:54 DEBUG : dir.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2026/04/18 06:50:54 DEBUG : dir.metadata: Open: flags=O_RDONLY 2026/04/18 06:50:54 DEBUG : dir.metadata: >Open: fd=dir.metadata (r), err= 2026/04/18 06:50:54 DEBUG : dir.metadata: >OpenFile: fd=dir.metadata (r), err= 2026/04/18 06:50:54 DEBUG : dir.metadata: ChunkedReader.openRange at 0 length 134217728 2026/04/18 06:50:54 DEBUG : dir.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2026/04/18 06:50:54 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:50:54 DEBUG : dir/sub: Looking for writers 2026/04/18 06:50:54 DEBUG : dir: Looking for writers 2026/04/18 06:50:54 DEBUG : file1: reading active writers 2026/04/18 06:50:54 DEBUG : sub: reading active writers 2026/04/18 06:50:54 DEBUG : file1.metadata: reading active writers 2026/04/18 06:50:54 DEBUG : Looking for writers 2026/04/18 06:50:54 DEBUG : dir: reading active writers 2026/04/18 06:50:54 DEBUG : dir.metadata: reading active writers 2026/04/18 06:50:54 DEBUG : >WaitForWriters: run.go:130: removing dir "dir" failed - try 1/3: directory not empty run.go:130: removing dir "dir" failed - try 2/3: directory not empty run.go:130: removing dir "dir" failed - try 3/3: directory not empty run.go:133: removing dir "dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestDirMetadataExtension Messages: directories --- FAIL: TestDirMetadataExtension (20.23s) === RUN TestFileMethods run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:51:10 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:51:10 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:51:12 DEBUG : dir/file1: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:51:12 DEBUG : dir/file1: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:51:13 DEBUG : dir/file1: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:51:13 DEBUG : dir/file1: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:51:14 DEBUG : dir/file1: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:51:16 DEBUG : dir/file1: Trying to read object after upload: try again in 3.2s (6/10) 2026/04/18 06:51:19 DEBUG : dir/file1: Trying to read object after upload: try again in 6.4s (7/10) 2026/04/18 06:51:27 DEBUG : dir/file1: Set virtual modtime to 2026-04-18 06:51:11 +0000 UTC 2026/04/18 06:51:27 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:51:27 DEBUG : dir: Looking for writers 2026/04/18 06:51:27 DEBUG : file1: reading active writers 2026/04/18 06:51:27 DEBUG : Looking for writers 2026/04/18 06:51:27 DEBUG : dir: reading active writers 2026/04/18 06:51:27 DEBUG : >WaitForWriters: --- PASS: TestFileMethods (20.74s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:51:30 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:51:30 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:51:38 DEBUG : Can set mod time: false file_test.go:97: can't set mod time 2026/04/18 06:51:38 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:51:38 DEBUG : dir: Looking for writers 2026/04/18 06:51:38 DEBUG : file1: reading active writers 2026/04/18 06:51:38 DEBUG : Looking for writers 2026/04/18 06:51:38 DEBUG : dir: reading active writers 2026/04/18 06:51:38 DEBUG : >WaitForWriters: --- PASS: TestFileSetModTime (11.18s) --- SKIP: TestFileSetModTime/cache=off,open=false,write=false (11.18s) === RUN TestFileOpenRead run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:51:41 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:51:41 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:51:46 DEBUG : dir/file1: Open: flags=O_RDONLY 2026/04/18 06:51:46 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2026/04/18 06:51:46 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 file_test.go:171: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:171 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:180 Error: Received unexpected error: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestFileOpenRead 2026/04/18 06:51:46 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:51:46 DEBUG : dir: Looking for writers 2026/04/18 06:51:46 DEBUG : file1: reading active writers 2026/04/18 06:51:46 DEBUG : Looking for writers 2026/04/18 06:51:46 DEBUG : dir: reading active writers 2026/04/18 06:51:46 DEBUG : >WaitForWriters: run.go:130: removing dir "dir" failed - try 1/3: directory not empty --- FAIL: TestFileOpenRead (9.04s) === RUN TestFileOpenWrite run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:51:51 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:51:51 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:51:54 DEBUG : dir/file1: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:51:54 DEBUG : dir/file1: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:51:55 DEBUG : dir/file1: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:51:55 DEBUG : dir/file1: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:51:56 DEBUG : dir/file1: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:51:59 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/04/18 06:51:59 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': File to upload is small (25 bytes), uploading instead of streaming 2026/04/18 06:51:59 DEBUG : dir/file1: Update: removing old file 2026/04/18 06:52:02 DEBUG : dir/file1: size = 25 (Linkbox root 'rclone-test-cinibob8wixa') 2026/04/18 06:52:02 DEBUG : dir/file1: size = 14 (Linkbox root 'rclone-test-cinibob8wixa') 2026/04/18 06:52:02 DEBUG : dir/file1: Sizes differ 2026/04/18 06:52:02 ERROR : dir/file1: corrupted on transfer 2026/04/18 06:52:02 ERROR : dir/file1: WriteFileHandle.New Rcat failed: corrupted on transfer file_test.go:241: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:241 Error: Received unexpected error: corrupted on transfer Test: TestFileOpenWrite 2026/04/18 06:52:02 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:52:02 DEBUG : dir: Looking for writers 2026/04/18 06:52:02 DEBUG : file1: reading active writers 2026/04/18 06:52:02 DEBUG : Looking for writers 2026/04/18 06:52:02 DEBUG : dir: reading active writers 2026/04/18 06:52:02 DEBUG : >WaitForWriters: run.go:130: removing file "dir/file1" failed - try 1/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "dir/file1" failed - try 2/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "dir/file1" failed - try 3/3: could not Remove: Linkbox error 403: not permit run.go:133: removing file "dir/file1" failed: could not Remove: Linkbox error 403: not permit run.go:130: removing dir "dir" failed - try 1/3: directory not empty run.go:130: removing dir "dir" failed - try 2/3: directory not empty run.go:130: removing dir "dir" failed - try 3/3: directory not empty run.go:133: removing dir "dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:241 Error: Should be true Test: TestFileOpenWrite Messages: listing wrong, want got dir/file1(1) (25) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:241 Error: Should be true Test: TestFileOpenWrite Messages: Unexpected file "dir/file1(1)" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:241 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestFileOpenWrite Messages: directories --- FAIL: TestFileOpenWrite (27.75s) === RUN TestFileRemove run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:52:18 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:52:18 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:52:21 DEBUG : dir/file1: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:52:21 DEBUG : dir/file1: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:52:21 DEBUG : dir/file1: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:52:22 DEBUG : dir/file1: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:52:23 DEBUG : dir/file1: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:52:25 DEBUG : dir/file1: Trying to read object after upload: try again in 3.2s (6/10) 2026/04/18 06:52:28 DEBUG : dir/file1: Trying to read object after upload: try again in 6.4s (7/10) 2026/04/18 06:52:35 DEBUG : dir/file1: Trying to read object after upload: try again in 12.8s (8/10) 2026/04/18 06:52:48 DEBUG : dir/file1: Trying to read object after upload: try again in 25.6s (9/10) 2026/04/18 06:53:13 DEBUG : dir/file1: Trying to read object after upload: try again in 51.2s (10/10) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": object not found 2026/04/18 06:54:05 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:05 DEBUG : Looking for writers 2026/04/18 06:54:05 DEBUG : >WaitForWriters: run.go:130: removing dir "dir" failed - try 1/3: directory not empty run.go:130: removing dir "dir" failed - try 2/3: directory not empty --- FAIL: TestFileRemove (113.49s) === RUN TestFileRemoveAll run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:12 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:12 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:13 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:13 DEBUG : Looking for writers 2026/04/18 06:54:13 DEBUG : >WaitForWriters: --- FAIL: TestFileRemoveAll (1.28s) === RUN TestFileOpen run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:13 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:13 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:14 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:14 DEBUG : Looking for writers 2026/04/18 06:54:14 DEBUG : >WaitForWriters: --- FAIL: TestFileOpen (1.03s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:14 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:14 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:15 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:15 DEBUG : Looking for writers 2026/04/18 06:54:15 DEBUG : >WaitForWriters: === RUN TestFileRename/minimal,forceCache=false run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:15 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:15 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:54:15 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:15 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:15 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:15 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:15 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:15 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:15 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:15 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:15 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:15 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:16 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:16 DEBUG : Looking for writers 2026/04/18 06:54:16 DEBUG : >WaitForWriters: 2026/04/18 06:54:16 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting === RUN TestFileRename/minimal,forceCache=true run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:16 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:16 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:54:16 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:16 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:16 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:16 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:16 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:16 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:16 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:16 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:16 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:16 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:17 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:17 DEBUG : Looking for writers 2026/04/18 06:54:17 DEBUG : >WaitForWriters: 2026/04/18 06:54:17 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=false run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:17 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:17 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:17 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:17 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:17 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:17 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:17 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:18 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:18 DEBUG : Looking for writers 2026/04/18 06:54:18 DEBUG : >WaitForWriters: 2026/04/18 06:54:18 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=true run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:18 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:18 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:18 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:18 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:18 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:18 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:18 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:19 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:19 DEBUG : Looking for writers 2026/04/18 06:54:19 DEBUG : >WaitForWriters: 2026/04/18 06:54:19 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting --- FAIL: TestFileRename (5.20s) --- FAIL: TestFileRename/off,forceCache=false (1.00s) --- FAIL: TestFileRename/minimal,forceCache=false (1.00s) --- FAIL: TestFileRename/minimal,forceCache=true (1.07s) --- FAIL: TestFileRename/writes,forceCache=false (1.12s) --- FAIL: TestFileRename/writes,forceCache=true (1.00s) === RUN TestReadFileHandleMethods run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:19 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:19 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:20 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:20 DEBUG : Looking for writers 2026/04/18 06:54:20 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (1.01s) === RUN TestReadFileHandleSeek run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:20 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:20 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:21 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:21 DEBUG : Looking for writers 2026/04/18 06:54:21 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleSeek (1.03s) === RUN TestReadFileHandleReadAt run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:21 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:21 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:22 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:22 DEBUG : Looking for writers 2026/04/18 06:54:22 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (1.07s) === RUN TestReadFileHandleFlush run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:22 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:22 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:23 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:23 DEBUG : Looking for writers 2026/04/18 06:54:23 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleFlush (1.01s) === RUN TestReadFileHandleRelease run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:23 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:23 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:24 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:24 DEBUG : Looking for writers 2026/04/18 06:54:24 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (1.01s) === RUN TestRWFileHandleMethodsRead run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:24 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:24 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:24 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:24 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:24 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:24 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:24 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:24 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:24 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:24 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:24 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:25 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:25 DEBUG : Looking for writers 2026/04/18 06:54:25 DEBUG : >WaitForWriters: 2026/04/18 06:54:25 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (1.01s) === RUN TestRWFileHandleSeek run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:25 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:25 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:25 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:25 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:25 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:25 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:25 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:25 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:25 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:25 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:25 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:26 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:26 DEBUG : Looking for writers 2026/04/18 06:54:26 DEBUG : >WaitForWriters: 2026/04/18 06:54:26 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (1.00s) === RUN TestRWFileHandleReadAt run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:26 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:26 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:26 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:26 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:26 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:26 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:26 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:26 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:26 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:26 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:26 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:27 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:27 DEBUG : Looking for writers 2026/04/18 06:54:27 DEBUG : >WaitForWriters: 2026/04/18 06:54:27 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (1.05s) === RUN TestRWFileHandleFlushRead run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:27 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:27 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:27 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:27 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:27 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:28 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:28 DEBUG : Looking for writers 2026/04/18 06:54:28 DEBUG : >WaitForWriters: 2026/04/18 06:54:28 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleFlushRead (1.02s) === RUN TestRWFileHandleReleaseRead run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:28 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:28 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:28 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:28 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:28 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:28 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:28 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:28 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:28 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:28 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:28 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:29 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:29 DEBUG : Looking for writers 2026/04/18 06:54:29 DEBUG : >WaitForWriters: 2026/04/18 06:54:29 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (1.01s) === RUN TestRWFileHandleWriteAt run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:29 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:29 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:29 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:29 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:29 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:29 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:29 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:29 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:29 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:29 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:29 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 06:54:29 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/04/18 06:54:30 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 06:54:30 DEBUG : file1: newRWFileHandle: 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): openPending: 2026/04/18 06:54:30 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2026/04/18 06:54:30 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): >openPending: err= 2026/04/18 06:54:30 DEBUG : file1: >newRWFileHandle: err= 2026/04/18 06:54:30 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:54:30 DEBUG : file1: >Open: fd=file1 (rw), err= 2026/04/18 06:54:30 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): _writeAt: size=7, off=0 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): >_writeAt: n=7, err= 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): _writeAt: size=6, off=5 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): >_writeAt: n=6, err= 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): close: 2026/04/18 06:54:30 DEBUG : file1: vfs cache: setting modification time to 2026-04-18 06:54:30.193306463 +0000 UTC m=+269.261018501 2026/04/18 06:54:30 INFO : file1: vfs cache: queuing for upload in 100ms 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): >close: err= 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): _writeAt: size=5, off=0 2026/04/18 06:54:30 DEBUG : file1(0x26ede343f380): >_writeAt: n=0, err=file already closed 2026/04/18 06:54:30 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:30 DEBUG : Looking for writers 2026/04/18 06:54:30 DEBUG : file1: reading active writers 2026/04/18 06:54:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/04/18 06:54:30 DEBUG : Looking for writers 2026/04/18 06:54:30 DEBUG : file1: reading active writers 2026/04/18 06:54:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/04/18 06:54:30 DEBUG : Looking for writers 2026/04/18 06:54:30 DEBUG : file1: reading active writers 2026/04/18 06:54:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/04/18 06:54:30 DEBUG : Looking for writers 2026/04/18 06:54:30 DEBUG : file1: reading active writers 2026/04/18 06:54:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/04/18 06:54:30 DEBUG : file1: vfs cache: starting upload 2026/04/18 06:54:30 DEBUG : Looking for writers 2026/04/18 06:54:30 DEBUG : file1: reading active writers 2026/04/18 06:54:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/04/18 06:54:30 DEBUG : Looking for writers 2026/04/18 06:54:30 DEBUG : file1: reading active writers 2026/04/18 06:54:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/04/18 06:54:30 DEBUG : Looking for writers 2026/04/18 06:54:30 DEBUG : file1: reading active writers 2026/04/18 06:54:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/04/18 06:54:31 DEBUG : Looking for writers 2026/04/18 06:54:31 DEBUG : file1: reading active writers 2026/04/18 06:54:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2026/04/18 06:54:32 DEBUG : file1: size = 11 OK 2026/04/18 06:54:32 INFO : file1: Copied (new) 2026/04/18 06:54:32 DEBUG : file1: vfs cache: fingerprint now "11" 2026/04/18 06:54:32 DEBUG : file1: vfs cache: writeback object to VFS layer 2026/04/18 06:54:32 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:54:32 INFO : file1: vfs cache: upload succeeded try #1 2026/04/18 06:54:32 DEBUG : Looking for writers 2026/04/18 06:54:32 DEBUG : file1: reading active writers 2026/04/18 06:54:32 DEBUG : >WaitForWriters: 2026/04/18 06:54:32 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:32 DEBUG : Looking for writers 2026/04/18 06:54:32 DEBUG : file1: reading active writers 2026/04/18 06:54:32 DEBUG : >WaitForWriters: 2026/04/18 06:54:32 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting --- PASS: TestRWFileHandleWriteAt (4.41s) === RUN TestRWFileHandleSizeCreateExisting run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:34 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:34 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: root is "/home/rclone/.cache/rclone" 2026/04/18 06:54:34 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:34 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/04/18 06:54:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-cinibob8wixa" 2026/04/18 06:54:34 INFO : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:290: Failed to put "dir/file1" to "Linkbox root 'rclone-test-cinibob8wixa'": Update second step failed: Linkbox error 403: not permit 2026/04/18 06:54:35 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:35 DEBUG : Looking for writers 2026/04/18 06:54:35 DEBUG : >WaitForWriters: 2026/04/18 06:54:35 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeCreateExisting (1.00s) === RUN TestUnicodeNormalization run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:39 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:39 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err= 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err= 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728 vfs_case_test.go:147: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:147 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:187 Error: Received unexpected error: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestUnicodeNormalization vfs_case_test.go:148: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:148 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:187 Error: Not equal: expected: 0 actual : 5 Test: TestUnicodeNormalization vfs_case_test.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:150 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:187 Error: Not equal: expected: "\x00\x00\x00\x00\x00" actual : "data1" Diff: --- Expected +++ Actual @@ -1 +1 @@ - +data1 Test: TestUnicodeNormalization 2026/04/18 06:54:39 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/04/18 06:54:39 DEBUG : 測試_Русский___ě_áñ: Open: flags=O_RDONLY 2026/04/18 06:54:39 DEBUG : 測試_Русский___ě_áñ: >Open: fd=測試_Русский___ě_áñ (r), err= 2026/04/18 06:54:39 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=測試_Русский___ě_áñ (r), err= 2026/04/18 06:54:39 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.openRange at 0 length 134217728 vfs_case_test.go:147: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:147 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:188 Error: Received unexpected error: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestUnicodeNormalization vfs_case_test.go:148: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:148 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:188 Error: Not equal: expected: 0 actual : 5 Test: TestUnicodeNormalization vfs_case_test.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:150 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:188 Error: Not equal: expected: "\x00\x00\x00\x00\x00" actual : "data2" Diff: --- Expected +++ Actual @@ -1 +1 @@ - +data2 Test: TestUnicodeNormalization 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err= 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err= 2026/04/18 06:54:39 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728 vfs_case_test.go:147: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:147 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:195 Error: Received unexpected error: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestUnicodeNormalization vfs_case_test.go:148: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:148 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:195 Error: Not equal: expected: 0 actual : 5 Test: TestUnicodeNormalization vfs_case_test.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:150 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_case_test.go:195 Error: Not equal: expected: "\x00\x00\x00\x00\x00" actual : "data1" Diff: --- Expected +++ Actual @@ -1 +1 @@ - +data1 Test: TestUnicodeNormalization 2026/04/18 06:54:40 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/04/18 06:54:40 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=, err=file does not exist 2026/04/18 06:54:40 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:40 DEBUG : Looking for writers 2026/04/18 06:54:40 DEBUG : normal name with no special characters.txt: reading active writers 2026/04/18 06:54:40 DEBUG : 測試_Русский___ě_áñ: reading active writers 2026/04/18 06:54:40 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestUnicodeNormalization (15.39s) === RUN TestWriteFileHandleFlush run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:54:50 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:54:50 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:54:50 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 06:54:50 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 06:54:50 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:54:50 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 06:54:50 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 06:54:50 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2026/04/18 06:54:50 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:54:50 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': File to upload is small (5 bytes), uploading instead of streaming 2026/04/18 06:54:52 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:54:53 DEBUG : file1: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:54:53 DEBUG : file1: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:54:54 DEBUG : file1: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:54:55 DEBUG : file1: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:54:56 DEBUG : file1: size = 5 OK 2026/04/18 06:54:56 DEBUG : file1: Size of src and dst objects identical 2026/04/18 06:54:56 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:54:56 DEBUG : file1: WriteFileHandle.Flush nothing to do 2026/04/18 06:54:56 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:54:56 DEBUG : Looking for writers 2026/04/18 06:54:56 DEBUG : file1: reading active writers 2026/04/18 06:54:56 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestWriteFileHandleFlush (15.29s) === RUN TestFileReadAtNonZeroLength run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:55:06 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:55:06 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:55:06 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/18 06:55:06 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/18 06:55:06 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:55:06 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/18 06:55:06 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/18 06:55:06 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:55:06 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': File to upload is small (100 bytes), uploading instead of streaming 2026/04/18 06:55:08 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:55:09 DEBUG : file1: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:55:09 DEBUG : file1: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:55:10 DEBUG : file1: size = 100 OK 2026/04/18 06:55:10 DEBUG : file1: Size of src and dst objects identical 2026/04/18 06:55:10 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/18 06:55:10 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/04/18 06:55:10 DEBUG : file1: Open: flags=O_RDONLY 2026/04/18 06:55:10 DEBUG : file1: >Open: fd=file1 (r), err= 2026/04/18 06:55:10 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2026/04/18 06:55:10 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 write_test.go:370: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:370 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Received unexpected error: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestFileReadAtNonZeroLength write_test.go:372: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:372 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Not equal: expected: 0 actual : 100 Test: TestFileReadAtNonZeroLength write_test.go:373: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:373 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Not equal: expected: []byte{0x67, 0x6f, 0x6b, 0x69, 0x68, 0x65, 0x67, 0x38, 0x71, 0x69, 0x6e, 0x75, 0x70, 0x6f, 0x7a, 0x31, 0x74, 0x75, 0x62, 0x65, 0x72, 0x61, 0x62, 0x35, 0x68, 0x61, 0x79, 0x61, 0x76, 0x69, 0x62, 0x32, 0x64, 0x69, 0x6a, 0x65, 0x79, 0x61, 0x77, 0x31, 0x70, 0x75, 0x66, 0x61, 0x77, 0x65, 0x6c, 0x33, 0x6c, 0x65, 0x7a, 0x65, 0x74, 0x65, 0x74, 0x31, 0x62, 0x61, 0x6e, 0x75, 0x63, 0x75, 0x66, 0x32, 0x77, 0x65, 0x73, 0x61, 0x62, 0x69, 0x6e, 0x30, 0x6d, 0x65, 0x78, 0x6f, 0x70, 0x6f, 0x78, 0x37, 0x78, 0x75, 0x77, 0x65, 0x78, 0x65, 0x7a, 0x36, 0x73, 0x69, 0x66, 0x69, 0x66, 0x65, 0x74, 0x38, 0x6a, 0x69, 0x6e, 0x65} actual : []byte{} Diff: --- Expected +++ Actual @@ -1,9 +1,2 @@ -([]uint8) (len=100) { - 00000000 67 6f 6b 69 68 65 67 38 71 69 6e 75 70 6f 7a 31 |gokiheg8qinupoz1| - 00000010 74 75 62 65 72 61 62 35 68 61 79 61 76 69 62 32 |tuberab5hayavib2| - 00000020 64 69 6a 65 79 61 77 31 70 75 66 61 77 65 6c 33 |dijeyaw1pufawel3| - 00000030 6c 65 7a 65 74 65 74 31 62 61 6e 75 63 75 66 32 |lezetet1banucuf2| - 00000040 77 65 73 61 62 69 6e 30 6d 65 78 6f 70 6f 78 37 |wesabin0mexopox7| - 00000050 78 75 77 65 78 65 7a 36 73 69 66 69 66 65 74 38 |xuwexez6sififet8| - 00000060 6a 69 6e 65 |jine| +([]uint8) { } Test: TestFileReadAtNonZeroLength 2026/04/18 06:55:10 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:55:10 DEBUG : Looking for writers 2026/04/18 06:55:10 DEBUG : file1: reading active writers 2026/04/18 06:55:10 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestFileReadAtNonZeroLength Messages: listing wrong, want got file1 (100) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestFileReadAtNonZeroLength Messages: Unexpected file "file1" --- FAIL: TestFileReadAtNonZeroLength (13.94s) === RUN TestZipManyFiles run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:55:20 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:55:20 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:55:25 DEBUG : flat/f001.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:55:25 DEBUG : flat/f001.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:55:26 DEBUG : flat/f001.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:55:26 DEBUG : flat/f001.txt: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:55:29 DEBUG : flat/f002.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:55:30 DEBUG : flat/f002.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:55:30 DEBUG : flat/f002.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:55:31 DEBUG : flat/f002.txt: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:55:32 DEBUG : flat/f002.txt: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:55:35 DEBUG : flat/f003.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:55:36 DEBUG : flat/f003.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:55:36 DEBUG : flat/f003.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:55:37 DEBUG : flat/f003.txt: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:55:40 DEBUG : flat/f004.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:55:40 DEBUG : flat/f004.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:55:40 DEBUG : flat/f004.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:55:41 DEBUG : flat/f004.txt: Trying to read object after upload: try again in 800ms (4/10) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:65 Error: Should be true Test: TestZipManyFiles Messages: listing wrong, want flat/f000.txt (7), flat/f001.txt (14), flat/f002.txt (21), flat/f003.txt (28), flat/f004.txt (35) got file1 (100), flat/f000.txt (7), flat/f001.txt (14), flat/f002.txt (21), flat/f003.txt (28), flat/f004.txt (35) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:65 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestZipManyFiles Messages: Unexpected file "file1" 2026/04/18 06:55:51 DEBUG : flat/f000.txt: Open: flags=O_RDONLY 2026/04/18 06:55:51 DEBUG : flat/f000.txt: >Open: fd=flat/f000.txt (r), err= 2026/04/18 06:55:51 DEBUG : flat/f000.txt: Set virtual modtime to 2026-04-18 06:55:22 +0000 UTC 2026/04/18 06:55:51 DEBUG : flat/f000.txt: ChunkedReader.openRange at 0 length 134217728 zip_test.go:71: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:71 Error: Received unexpected error: create zip copy: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestZipManyFiles 2026/04/18 06:55:51 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:55:51 DEBUG : flat: Looking for writers 2026/04/18 06:55:51 DEBUG : f003.txt: reading active writers 2026/04/18 06:55:51 DEBUG : f004.txt: reading active writers 2026/04/18 06:55:51 DEBUG : f000.txt: reading active writers 2026/04/18 06:55:51 DEBUG : f001.txt: reading active writers 2026/04/18 06:55:51 DEBUG : f002.txt: reading active writers 2026/04/18 06:55:51 DEBUG : Looking for writers 2026/04/18 06:55:51 DEBUG : file1: reading active writers 2026/04/18 06:55:51 DEBUG : flat: reading active writers 2026/04/18 06:55:51 DEBUG : >WaitForWriters: run.go:130: removing file "file1" failed - try 1/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "file1" failed - try 2/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "file1" failed - try 3/3: could not Remove: Linkbox error 403: not permit run.go:133: removing file "file1" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:71 Error: Should be true Test: TestZipManyFiles Messages: listing wrong, want got file1 (100) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:71 Error: Should be true Test: TestZipManyFiles Messages: Unexpected file "file1" --- FAIL: TestZipManyFiles (52.15s) === RUN TestZipManySubDirs run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:56:12 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:56:12 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:56:15 DEBUG : a/top.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:56:16 DEBUG : a/top.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:56:16 DEBUG : a/top.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:56:17 DEBUG : a/top.txt: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:56:18 DEBUG : a/top.txt: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:56:19 DEBUG : a/top.txt: Trying to read object after upload: try again in 3.2s (6/10) 2026/04/18 06:56:26 DEBUG : a/b/mid.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:56:27 DEBUG : a/b/mid.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:56:27 DEBUG : a/b/mid.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:56:28 DEBUG : a/b/mid.txt: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:56:29 DEBUG : a/b/mid.txt: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:56:30 DEBUG : a/b/mid.txt: Trying to read object after upload: try again in 3.2s (6/10) 2026/04/18 06:56:38 DEBUG : a/b/c/deep.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:56:39 DEBUG : a/b/c/deep.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:56:39 DEBUG : a/b/c/deep.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:56:40 DEBUG : a/b/c/deep.txt: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:56:41 DEBUG : a/b/c/deep.txt: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:56:43 DEBUG : a/b/c/deep.txt: Open: flags=O_RDONLY 2026/04/18 06:56:43 DEBUG : a/b/c/deep.txt: >Open: fd=a/b/c/deep.txt (r), err= 2026/04/18 06:56:43 DEBUG : a/b/c/deep.txt: Set virtual modtime to 2026-04-18 06:56:36 +0000 UTC 2026/04/18 06:56:43 DEBUG : a/b/c/deep.txt: ChunkedReader.openRange at 0 length 134217728 zip_test.go:101: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:101 Error: Received unexpected error: create zip copy: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestZipManySubDirs 2026/04/18 06:56:43 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:56:43 DEBUG : a/b/c: Looking for writers 2026/04/18 06:56:43 DEBUG : deep.txt: reading active writers 2026/04/18 06:56:43 DEBUG : a/b: Looking for writers 2026/04/18 06:56:43 DEBUG : c: reading active writers 2026/04/18 06:56:43 DEBUG : mid.txt: reading active writers 2026/04/18 06:56:43 DEBUG : a: Looking for writers 2026/04/18 06:56:43 DEBUG : b: reading active writers 2026/04/18 06:56:43 DEBUG : top.txt: reading active writers 2026/04/18 06:56:43 DEBUG : Looking for writers 2026/04/18 06:56:43 DEBUG : a: reading active writers 2026/04/18 06:56:43 DEBUG : file1: reading active writers 2026/04/18 06:56:43 DEBUG : >WaitForWriters: run.go:130: removing file "file1" failed - try 1/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "file1" failed - try 2/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "file1" failed - try 3/3: could not Remove: Linkbox error 403: not permit run.go:133: removing file "file1" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:101 Error: Should be true Test: TestZipManySubDirs Messages: listing wrong, want got file1 (100) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:101 Error: Should be true Test: TestZipManySubDirs Messages: Unexpected file "file1" --- FAIL: TestZipManySubDirs (52.62s) === RUN TestZipLargeFiles run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:57:04 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:57:04 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:57:10 DEBUG : bigdir/big.bin: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:57:10 DEBUG : bigdir/big.bin: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:57:11 DEBUG : bigdir/big.bin: Open: flags=O_RDONLY 2026/04/18 06:57:11 DEBUG : bigdir/big.bin: >Open: fd=bigdir/big.bin (r), err= 2026/04/18 06:57:11 DEBUG : bigdir/big.bin: Set virtual modtime to 2026-04-18 06:57:09 +0000 UTC 2026/04/18 06:57:11 DEBUG : bigdir/big.bin: ChunkedReader.openRange at 0 length 134217728 zip_test.go:131: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:131 Error: Received unexpected error: create zip copy: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestZipLargeFiles 2026/04/18 06:57:11 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:57:11 DEBUG : bigdir: Looking for writers 2026/04/18 06:57:11 DEBUG : big.bin: reading active writers 2026/04/18 06:57:11 DEBUG : Looking for writers 2026/04/18 06:57:11 DEBUG : bigdir: reading active writers 2026/04/18 06:57:11 DEBUG : file1: reading active writers 2026/04/18 06:57:11 DEBUG : >WaitForWriters: run.go:130: removing file "file1" failed - try 1/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "file1" failed - try 2/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "file1" failed - try 3/3: could not Remove: Linkbox error 403: not permit run.go:133: removing file "file1" failed: could not Remove: Linkbox error 403: not permit run.go:130: removing dir "bigdir" failed - try 1/3: directory not empty run.go:130: removing dir "bigdir" failed - try 2/3: directory not empty run.go:130: removing dir "bigdir" failed - try 3/3: directory not empty run.go:133: removing dir "bigdir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:131 Error: Should be true Test: TestZipLargeFiles Messages: listing wrong, want got file1 (100) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:131 Error: Should be true Test: TestZipLargeFiles Messages: Unexpected file "file1" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:131 Error: Not equal: expected: []string{} actual : []string{"bigdir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=6) "bigdir" } Test: TestZipLargeFiles Messages: directories --- FAIL: TestZipLargeFiles (24.56s) === RUN TestZipDirsInRoot run.go:185: Remote "Linkbox root 'rclone-test-cinibob8wixa'", Local "Local file system at /tmp/rclone3066160285", Modify Window "876000h0m0s" 2026/04/18 06:57:29 INFO : Linkbox root 'rclone-test-cinibob8wixa': poll-interval is not supported by this remote 2026/04/18 06:57:29 NOTICE: Linkbox root 'rclone-test-cinibob8wixa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/18 06:57:33 DEBUG : dir1/a.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:57:33 DEBUG : dir1/a.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:57:33 DEBUG : dir1/a.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:57:38 DEBUG : dir2/b.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:57:38 DEBUG : dir2/b.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:57:42 DEBUG : dir3/c.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/04/18 06:57:42 DEBUG : dir3/c.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/04/18 06:57:43 DEBUG : dir3/c.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/04/18 06:57:43 DEBUG : dir3/c.txt: Trying to read object after upload: try again in 800ms (4/10) 2026/04/18 06:57:44 DEBUG : dir3/c.txt: Trying to read object after upload: try again in 1.6s (5/10) 2026/04/18 06:57:46 DEBUG : dir3/c.txt: Trying to read object after upload: try again in 3.2s (6/10) 2026/04/18 06:57:50 DEBUG : dir1/a.txt: Open: flags=O_RDONLY 2026/04/18 06:57:50 DEBUG : dir1/a.txt: >Open: fd=dir1/a.txt (r), err= 2026/04/18 06:57:50 DEBUG : dir1/a.txt: Set virtual modtime to 2026-04-18 06:57:31 +0000 UTC 2026/04/18 06:57:50 DEBUG : dir1/a.txt: ChunkedReader.openRange at 0 length 134217728 zip_test.go:148: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:148 Error: Received unexpected error: create zip copy: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestZipDirsInRoot 2026/04/18 06:57:50 DEBUG : WaitForWriters: timeout=30s 2026/04/18 06:57:50 DEBUG : bigdir: Looking for writers 2026/04/18 06:57:50 DEBUG : dir1: Looking for writers 2026/04/18 06:57:50 DEBUG : a.txt: reading active writers 2026/04/18 06:57:50 DEBUG : dir2: Looking for writers 2026/04/18 06:57:50 DEBUG : dir3: Looking for writers 2026/04/18 06:57:50 DEBUG : Looking for writers 2026/04/18 06:57:50 DEBUG : bigdir: reading active writers 2026/04/18 06:57:50 DEBUG : dir1: reading active writers 2026/04/18 06:57:50 DEBUG : dir2: reading active writers 2026/04/18 06:57:50 DEBUG : dir3: reading active writers 2026/04/18 06:57:50 DEBUG : file1: reading active writers 2026/04/18 06:57:50 DEBUG : >WaitForWriters: run.go:130: removing file "file1" failed - try 1/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "file1" failed - try 2/3: could not Remove: Linkbox error 403: not permit run.go:130: removing file "file1" failed - try 3/3: could not Remove: Linkbox error 403: not permit run.go:133: removing file "file1" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:148 Error: Should be true Test: TestZipDirsInRoot Messages: listing wrong, want got file1 (100) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/zip_test.go:148 Error: Should be true Test: TestZipDirsInRoot Messages: Unexpected file "file1" --- FAIL: TestZipDirsInRoot (43.72s) FAIL 2026/04/18 06:58:13 DEBUG : Linkbox root 'rclone-test-cinibob8wixa': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirCreate|TestDirFileOpen|TestDirMetadataExtension|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleWriteAt|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestWriteFileHandleFlush|TestZipDirsInRoot|TestZipLargeFiles|TestZipManyFiles|TestZipManySubDirs)$|^TestFileRename$/^(minimal,forceCache=false|minimal,forceCache=true|off,forceCache=false|writes,forceCache=false|writes,forceCache=true)$|^TestFileSetModTime$/^cache=off,open=false,write=false$'" - Finished ERROR in 8m13.705034932s (try 5/5): exit status 1: Failed [TestDirFileOpen TestDirMetadataExtension 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 TestReadFileHandleMethods TestReadFileHandleSeek TestReadFileHandleReadAt TestReadFileHandleFlush TestReadFileHandleRelease TestRWFileHandleMethodsRead TestRWFileHandleSeek TestRWFileHandleReadAt TestRWFileHandleFlushRead TestRWFileHandleReleaseRead TestRWFileHandleSizeCreateExisting TestUnicodeNormalization TestFileReadAtNonZeroLength TestZipManyFiles TestZipManySubDirs TestZipLargeFiles TestZipDirsInRoot]