"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestFileReadAtNonZeroLength|TestReadFileHandleMethods|TestReadFileHandleRelease|TestZipManyFiles|TestZipManySubDirs)$'" - Starting (try 4/5) 2026/04/30 03:47:50 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-nufebuv1buze" 2026/04/30 03:47:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/04/30 03:47:50 DEBUG : Linkbox root 'rclone-test-nufebuv1buze': Using cached web token 2026/04/30 03:47:51 DEBUG : Creating backend with remote "/tmp/rclone1644634723" === RUN TestReadFileHandleMethods run.go:185: Remote "Linkbox root 'rclone-test-nufebuv1buze'", Local "Local file system at /tmp/rclone1644634723", Modify Window "876000h0m0s" 2026/04/30 03:47:51 INFO : Linkbox root 'rclone-test-nufebuv1buze': poll-interval is not supported by this remote 2026/04/30 03:47:51 NOTICE: Linkbox root 'rclone-test-nufebuv1buze': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/30 03:47:56 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/04/30 03:47:56 DEBUG : dir/file1: Open: flags=O_RDONLY 2026/04/30 03:47:56 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2026/04/30 03:47:56 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2026/04/30 03:47:56 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 2026/04/30 03:47:56 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 2026/04/30 03:47:57 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)(0x293ae3699d20)}) Test: TestReadFileHandleMethods 2026/04/30 03:47:57 DEBUG : WaitForWriters: timeout=30s 2026/04/30 03:47:57 DEBUG : dir: Looking for writers 2026/04/30 03:47:57 DEBUG : file1: reading active writers 2026/04/30 03:47:57 DEBUG : Looking for writers 2026/04/30 03:47:57 DEBUG : dir: reading active writers 2026/04/30 03:47:57 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (9.77s) === RUN TestReadFileHandleRelease run.go:185: Remote "Linkbox root 'rclone-test-nufebuv1buze'", Local "Local file system at /tmp/rclone1644634723", Modify Window "876000h0m0s" 2026/04/30 03:48:00 INFO : Linkbox root 'rclone-test-nufebuv1buze': poll-interval is not supported by this remote 2026/04/30 03:48:00 NOTICE: Linkbox root 'rclone-test-nufebuv1buze': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/30 03:48:05 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/04/30 03:48:05 DEBUG : dir/file1: Open: flags=O_RDONLY 2026/04/30 03:48:05 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2026/04/30 03:48:05 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2026/04/30 03:48:05 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2026/04/30 03:48:05 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 45h53m51.784895477s different from this computer 2026/04/30 03:48:05 DEBUG : dir/file1: ChunkedReader.Read at 0 length 256 chunkOffset 0 chunkSize 134217728 2026/04/30 03:48:05 DEBUG : dir/file1: ReadFileHandle.Release closing 2026/04/30 03:48:05 DEBUG : dir/file1: ReadFileHandle.Release nothing to do 2026/04/30 03:48:05 DEBUG : WaitForWriters: timeout=30s 2026/04/30 03:48:05 DEBUG : dir: Looking for writers 2026/04/30 03:48:05 DEBUG : file1: reading active writers 2026/04/30 03:48:05 DEBUG : Looking for writers 2026/04/30 03:48:05 DEBUG : dir: reading active writers 2026/04/30 03:48:05 DEBUG : >WaitForWriters: --- PASS: TestReadFileHandleRelease (8.46s) === RUN TestFileReadAtNonZeroLength run.go:185: Remote "Linkbox root 'rclone-test-nufebuv1buze'", Local "Local file system at /tmp/rclone1644634723", Modify Window "876000h0m0s" 2026/04/30 03:48:09 INFO : Linkbox root 'rclone-test-nufebuv1buze': poll-interval is not supported by this remote 2026/04/30 03:48:09 NOTICE: Linkbox root 'rclone-test-nufebuv1buze': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/30 03:48:09 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/04/30 03:48:09 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/04/30 03:48:09 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/30 03:48:09 DEBUG : file1: >Open: fd=file1 (w), err= 2026/04/30 03:48:09 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/04/30 03:48:09 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/30 03:48:09 DEBUG : Linkbox root 'rclone-test-nufebuv1buze': File to upload is small (100 bytes), uploading instead of streaming 2026/04/30 03:48:12 DEBUG : file1: size = 100 OK 2026/04/30 03:48:12 NOTICE: Linkbox root 'rclone-test-nufebuv1buze': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/04/30 03:48:12 DEBUG : file1: Size of src and dst objects identical 2026/04/30 03:48:12 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/04/30 03:48:12 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/04/30 03:48:12 DEBUG : file1: Open: flags=O_RDONLY 2026/04/30 03:48:12 DEBUG : file1: >Open: fd=file1 (r), err= 2026/04/30 03:48:12 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2026/04/30 03:48:12 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2026/04/30 03:48:12 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2026/04/30 03:48:12 DEBUG : WaitForWriters: timeout=30s 2026/04/30 03:48:12 DEBUG : Looking for writers 2026/04/30 03:48:12 DEBUG : file1: reading active writers 2026/04/30 03:48:12 DEBUG : >WaitForWriters: --- PASS: TestFileReadAtNonZeroLength (4.90s) === RUN TestZipManyFiles run.go:185: Remote "Linkbox root 'rclone-test-nufebuv1buze'", Local "Local file system at /tmp/rclone1644634723", Modify Window "876000h0m0s" 2026/04/30 03:48:14 INFO : Linkbox root 'rclone-test-nufebuv1buze': poll-interval is not supported by this remote 2026/04/30 03:48:14 NOTICE: Linkbox root 'rclone-test-nufebuv1buze': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/30 03:48:26 DEBUG : flat/f000.txt: Open: flags=O_RDONLY 2026/04/30 03:48:26 DEBUG : flat/f000.txt: >Open: fd=flat/f000.txt (r), err= 2026/04/30 03:48:26 DEBUG : flat/f000.txt: Set virtual modtime to 2026-04-30 03:48:16 +0000 UTC 2026/04/30 03:48:26 DEBUG : flat/f000.txt: ChunkedReader.openRange at 0 length 134217728 2026/04/30 03:48:26 DEBUG : flat/f000.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2026/04/30 03:48:26 DEBUG : flat/f001.txt: Open: flags=O_RDONLY 2026/04/30 03:48:26 DEBUG : flat/f001.txt: >Open: fd=flat/f001.txt (r), err= 2026/04/30 03:48:26 DEBUG : flat/f001.txt: Set virtual modtime to 2026-04-30 03:48:18 +0000 UTC 2026/04/30 03:48:26 DEBUG : flat/f001.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/30 03:48:26 DEBUG : WaitForWriters: timeout=30s 2026/04/30 03:48:26 DEBUG : flat: Looking for writers 2026/04/30 03:48:26 DEBUG : f000.txt: reading active writers 2026/04/30 03:48:26 DEBUG : f001.txt: reading active writers 2026/04/30 03:48:26 DEBUG : f002.txt: reading active writers 2026/04/30 03:48:26 DEBUG : f003.txt: reading active writers 2026/04/30 03:48:26 DEBUG : f004.txt: reading active writers 2026/04/30 03:48:26 DEBUG : Looking for writers 2026/04/30 03:48:26 DEBUG : flat: reading active writers 2026/04/30 03:48:26 DEBUG : >WaitForWriters: --- FAIL: TestZipManyFiles (21.08s) === RUN TestZipManySubDirs run.go:185: Remote "Linkbox root 'rclone-test-nufebuv1buze'", Local "Local file system at /tmp/rclone1644634723", Modify Window "876000h0m0s" 2026/04/30 03:48:35 INFO : Linkbox root 'rclone-test-nufebuv1buze': poll-interval is not supported by this remote 2026/04/30 03:48:35 NOTICE: Linkbox root 'rclone-test-nufebuv1buze': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/04/30 03:48:46 DEBUG : a/b/c/deep.txt: Open: flags=O_RDONLY 2026/04/30 03:48:46 DEBUG : a/b/c/deep.txt: >Open: fd=a/b/c/deep.txt (r), err= 2026/04/30 03:48:46 DEBUG : a/b/c/deep.txt: Set virtual modtime to 2026-04-30 03:48:44 +0000 UTC 2026/04/30 03:48:46 DEBUG : a/b/c/deep.txt: ChunkedReader.openRange at 0 length 134217728 2026/04/30 03:48:46 DEBUG : a/b/c/deep.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2026/04/30 03:48:46 DEBUG : a/b/mid.txt: Open: flags=O_RDONLY 2026/04/30 03:48:46 DEBUG : a/b/mid.txt: >Open: fd=a/b/mid.txt (r), err= 2026/04/30 03:48:46 DEBUG : a/b/mid.txt: Set virtual modtime to 2026-04-30 03:48:40 +0000 UTC 2026/04/30 03:48:46 DEBUG : a/b/mid.txt: ChunkedReader.openRange at 0 length 134217728 2026/04/30 03:48:46 DEBUG : a/b/mid.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2026/04/30 03:48:46 DEBUG : a/top.txt: Open: flags=O_RDONLY 2026/04/30 03:48:46 DEBUG : a/top.txt: >Open: fd=a/top.txt (r), err= 2026/04/30 03:48:46 DEBUG : a/top.txt: Set virtual modtime to 2026-04-30 03:48:37 +0000 UTC 2026/04/30 03:48:46 DEBUG : a/top.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/30 03:48:46 DEBUG : WaitForWriters: timeout=30s 2026/04/30 03:48:46 DEBUG : a/b/c: Looking for writers 2026/04/30 03:48:46 DEBUG : deep.txt: reading active writers 2026/04/30 03:48:46 DEBUG : a/b: Looking for writers 2026/04/30 03:48:46 DEBUG : c: reading active writers 2026/04/30 03:48:46 DEBUG : mid.txt: reading active writers 2026/04/30 03:48:46 DEBUG : a: Looking for writers 2026/04/30 03:48:46 DEBUG : b: reading active writers 2026/04/30 03:48:46 DEBUG : top.txt: reading active writers 2026/04/30 03:48:46 DEBUG : Looking for writers 2026/04/30 03:48:46 DEBUG : a: reading active writers 2026/04/30 03:48:46 DEBUG : >WaitForWriters: --- FAIL: TestZipManySubDirs (21.33s) FAIL 2026/04/30 03:48:56 DEBUG : Linkbox root 'rclone-test-nufebuv1buze': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestFileReadAtNonZeroLength|TestReadFileHandleMethods|TestReadFileHandleRelease|TestZipManyFiles|TestZipManySubDirs)$'" - Finished ERROR in 1m7.514270071s (try 4/5): exit status 1: Failed [TestReadFileHandleMethods TestZipManyFiles TestZipManySubDirs]