"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestFileOpenRead|TestFileReadAtNonZeroLength|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization)$'" - Starting (try 4/5) 2025/03/10 01:59:34 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-mazicup2cowe" 2025/03/10 01:59:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/03/10 01:59:35 DEBUG : Creating backend with remote "/tmp/rclone3580850779" === RUN TestFileOpenRead run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 01:59:35 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 01:59:35 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/03/10 01:59:42 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/03/10 01:59:42 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/03/10 01:59:42 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 2025/03/10 01:59:42 DEBUG : WaitForWriters: timeout=30s 2025/03/10 01:59:42 DEBUG : dir: Looking for writers 2025/03/10 01:59:42 DEBUG : file1: reading active writers 2025/03/10 01:59:42 DEBUG : : Looking for writers 2025/03/10 01:59:42 DEBUG : dir: reading active writers 2025/03/10 01:59:42 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenRead (11.03s) === RUN TestReadFileHandleMethods run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 01:59:46 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 01:59:46 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/03/10 01:59:51 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/03/10 01:59:51 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/03/10 01:59:51 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/03/10 01:59:51 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/03/10 01:59:51 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 read_test.go:34: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:34 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:58 Error: Received unexpected error: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestReadFileHandleMethods read_test.go:58: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:58 Error: Not equal: expected: "0" actual : "" Diff: --- Expected +++ Actual @@ -1 +1 @@ -0 + Test: TestReadFileHandleMethods 2025/03/10 01:59:51 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 read_test.go:34: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:34 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:61 Error: Received unexpected error: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestReadFileHandleMethods read_test.go:61: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:61 Error: Not equal: expected: "123456789abcdef" actual : "" Diff: --- Expected +++ Actual @@ -1 +1 @@ -123456789abcdef + Test: TestReadFileHandleMethods 2025/03/10 01:59:52 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 read_test.go:66: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:66 Error: Not equal: expected: *errors.errorString(&errors.errorString{s:"EOF"}) actual : *fmt.wrapError(&fmt.wrapError{msg:"Open failed: HTTP error 403 (403 Forbidden) returned body: \"ip incorrect\"", err:(*errors.errorString)(0xc00062be00)}) Test: TestReadFileHandleMethods 2025/03/10 01:59:52 DEBUG : WaitForWriters: timeout=30s 2025/03/10 01:59:52 DEBUG : dir: Looking for writers 2025/03/10 01:59:52 DEBUG : file1: reading active writers 2025/03/10 01:59:52 DEBUG : : Looking for writers 2025/03/10 01:59:52 DEBUG : dir: reading active writers 2025/03/10 01:59:52 DEBUG : >WaitForWriters: fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestReadFileHandleMethods Messages: directories --- FAIL: TestReadFileHandleMethods (19.00s) === RUN TestReadFileHandleSeek run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:05 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:05 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:07 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:07 DEBUG : : Looking for writers 2025/03/10 02:00:07 DEBUG : >WaitForWriters: run.go:125: removing dir "dir" failed - try 1/3: directory not found run.go:125: removing dir "dir" failed - try 2/3: directory not found run.go:125: removing dir "dir" failed - try 3/3: directory not found run.go:128: removing dir "dir" failed: directory not found --- FAIL: TestReadFileHandleSeek (6.92s) === RUN TestReadFileHandleReadAt run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:12 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:12 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:13 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:13 DEBUG : : Looking for writers 2025/03/10 02:00:13 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (1.66s) === RUN TestReadFileHandleFlush run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:14 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:14 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:15 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:15 DEBUG : : Looking for writers 2025/03/10 02:00:15 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleFlush (1.49s) === RUN TestReadFileHandleRelease run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:15 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:15 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:16 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:16 DEBUG : : Looking for writers 2025/03/10 02:00:16 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (1.42s) === RUN TestRWFileHandleMethodsRead run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:17 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:17 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: root is "/home/rclone/.cache/rclone" 2025/03/10 02:00:17 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:17 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:17 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:17 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:17 INFO : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:17 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:17 DEBUG : : Looking for writers 2025/03/10 02:00:17 DEBUG : >WaitForWriters: 2025/03/10 02:00:17 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (1.48s) === RUN TestRWFileHandleSeek run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:18 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:18 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: root is "/home/rclone/.cache/rclone" 2025/03/10 02:00:18 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:18 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:18 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:18 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:18 INFO : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:19 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:19 DEBUG : : Looking for writers 2025/03/10 02:00:19 DEBUG : >WaitForWriters: 2025/03/10 02:00:19 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (1.70s) === RUN TestRWFileHandleReadAt run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:20 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:20 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: root is "/home/rclone/.cache/rclone" 2025/03/10 02:00:20 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:20 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:20 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:20 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:20 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:20 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:20 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:20 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:20 INFO : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:21 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:21 DEBUG : : Looking for writers 2025/03/10 02:00:21 DEBUG : >WaitForWriters: 2025/03/10 02:00:21 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (1.43s) === RUN TestRWFileHandleFlushRead run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:21 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:21 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: root is "/home/rclone/.cache/rclone" 2025/03/10 02:00:21 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:21 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:21 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:21 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:21 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:21 INFO : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:22 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:22 DEBUG : : Looking for writers 2025/03/10 02:00:22 DEBUG : >WaitForWriters: 2025/03/10 02:00:22 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleFlushRead (1.61s) === RUN TestRWFileHandleReleaseRead run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:23 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:23 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: root is "/home/rclone/.cache/rclone" 2025/03/10 02:00:23 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:23 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:23 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:23 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/03/10 02:00:23 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-mazicup2cowe" 2025/03/10 02:00:23 INFO : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-mazicup2cowe'": Update second step failed: Linkbox error 403: not permit 2025/03/10 02:00:24 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:24 DEBUG : : Looking for writers 2025/03/10 02:00:24 DEBUG : >WaitForWriters: 2025/03/10 02:00:24 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (1.61s) === RUN TestUnicodeNormalization run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:00:27 DEBUG : normal name with no special characters.txt: Trying to read object after upload: try again in 100ms (1/10) 2025/03/10 02:00:27 DEBUG : normal name with no special characters.txt: Trying to read object after upload: try again in 200ms (2/10) 2025/03/10 02:00:28 DEBUG : normal name with no special characters.txt: Trying to read object after upload: try again in 400ms (3/10) 2025/03/10 02:00:28 DEBUG : normal name with no special characters.txt: Trying to read object after upload: try again in 800ms (4/10) 2025/03/10 02:00:29 DEBUG : normal name with no special characters.txt: Trying to read object after upload: try again in 1.6s (5/10) 2025/03/10 02:00:31 DEBUG : normal name with no special characters.txt: Trying to read object after upload: try again in 3.2s (6/10) 2025/03/10 02:00:35 DEBUG : normal name with no special characters.txt: Trying to read object after upload: try again in 6.4s (7/10) 2025/03/10 02:00:41 DEBUG : normal name with no special characters.txt: Trying to read object after upload: try again in 12.8s (8/10) 2025/03/10 02:00:58 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:00:58 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err= 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err= 2025/03/10 02:00:58 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 2025/03/10 02:00:58 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/03/10 02:00:58 DEBUG : 測試_Русский___ě_áñ: Open: flags=O_RDONLY 2025/03/10 02:00:58 DEBUG : 測試_Русский___ě_áñ: >Open: fd=測試_Русский___ě_áñ (r), err= 2025/03/10 02:00:58 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=測試_Русский___ě_áñ (r), err= 2025/03/10 02:00:58 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 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err= 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err= 2025/03/10 02:00:58 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 2025/03/10 02:00:58 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/03/10 02:00:58 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=, err=file does not exist 2025/03/10 02:00:58 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:00:58 DEBUG : : Looking for writers 2025/03/10 02:00:58 DEBUG : normal name with no special characters.txt: reading active writers 2025/03/10 02:00:58 DEBUG : 測試_Русский___ě_áñ: reading active writers 2025/03/10 02:00:58 DEBUG : >WaitForWriters: --- FAIL: TestUnicodeNormalization (37.51s) === RUN TestFileReadAtNonZeroLength run.go:180: Remote "Linkbox root 'rclone-test-mazicup2cowe'", Local "Local file system at /tmp/rclone3580850779", Modify Window "876000h0m0s" 2025/03/10 02:01:02 INFO : Linkbox root 'rclone-test-mazicup2cowe': poll-interval is not supported by this remote 2025/03/10 02:01:02 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/03/10 02:01:02 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/03/10 02:01:02 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/03/10 02:01:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/03/10 02:01:02 DEBUG : file1: >Open: fd=file1 (w), err= 2025/03/10 02:01:02 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/03/10 02:01:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/03/10 02:01:02 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': File to upload is small (100 bytes), uploading instead of streaming 2025/03/10 02:01:05 NOTICE: Linkbox root 'rclone-test-mazicup2cowe': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/03/10 02:01:05 DEBUG : file1: Size of src and dst objects identical 2025/03/10 02:01:05 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/03/10 02:01:05 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2025/03/10 02:01:05 DEBUG : file1: Open: flags=O_RDONLY 2025/03/10 02:01:05 DEBUG : file1: >Open: fd=file1 (r), err= 2025/03/10 02:01:05 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2025/03/10 02:01:05 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{0x73, 0x75, 0x63, 0x75, 0x6e, 0x69, 0x70, 0x31, 0x68, 0x69, 0x6e, 0x75, 0x63, 0x69, 0x77, 0x30, 0x63, 0x65, 0x6b, 0x61, 0x70, 0x61, 0x6a, 0x35, 0x70, 0x69, 0x7a, 0x69, 0x68, 0x61, 0x77, 0x33, 0x70, 0x65, 0x76, 0x6f, 0x6a, 0x75, 0x76, 0x38, 0x68, 0x69, 0x6c, 0x6f, 0x6a, 0x61, 0x70, 0x33, 0x76, 0x69, 0x7a, 0x61, 0x64, 0x61, 0x6a, 0x32, 0x63, 0x69, 0x77, 0x69, 0x74, 0x6f, 0x63, 0x32, 0x70, 0x75, 0x77, 0x65, 0x77, 0x61, 0x64, 0x31, 0x79, 0x65, 0x71, 0x75, 0x62, 0x61, 0x73, 0x32, 0x68, 0x61, 0x77, 0x6f, 0x6b, 0x69, 0x67, 0x39, 0x67, 0x65, 0x74, 0x65, 0x62, 0x65, 0x62, 0x35, 0x72, 0x75, 0x66, 0x69} actual : []byte{} Diff: --- Expected +++ Actual @@ -1,9 +1,2 @@ -([]uint8) (len=100) { - 00000000 73 75 63 75 6e 69 70 31 68 69 6e 75 63 69 77 30 |sucunip1hinuciw0| - 00000010 63 65 6b 61 70 61 6a 35 70 69 7a 69 68 61 77 33 |cekapaj5pizihaw3| - 00000020 70 65 76 6f 6a 75 76 38 68 69 6c 6f 6a 61 70 33 |pevojuv8hilojap3| - 00000030 76 69 7a 61 64 61 6a 32 63 69 77 69 74 6f 63 32 |vizadaj2ciwitoc2| - 00000040 70 75 77 65 77 61 64 31 79 65 71 75 62 61 73 32 |puwewad1yequbas2| - 00000050 68 61 77 6f 6b 69 67 39 67 65 74 65 62 65 62 35 |hawokig9getebeb5| - 00000060 72 75 66 69 |rufi| +([]uint8) { } Test: TestFileReadAtNonZeroLength 2025/03/10 02:01:05 DEBUG : WaitForWriters: timeout=30s 2025/03/10 02:01:05 DEBUG : : Looking for writers 2025/03/10 02:01:05 DEBUG : file1: reading active writers 2025/03/10 02:01:05 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtNonZeroLength (5.50s) FAIL 2025/03/10 02:01:07 DEBUG : Linkbox root 'rclone-test-mazicup2cowe': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestFileOpenRead|TestFileReadAtNonZeroLength|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization)$'" - Finished ERROR in 1m34.484001852s (try 4/5): exit status 1: Failed [TestFileOpenRead TestReadFileHandleMethods TestReadFileHandleSeek TestReadFileHandleReadAt TestReadFileHandleFlush TestReadFileHandleRelease TestRWFileHandleMethodsRead TestRWFileHandleSeek TestRWFileHandleReadAt TestRWFileHandleFlushRead TestRWFileHandleReleaseRead TestUnicodeNormalization TestFileReadAtNonZeroLength]