"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCaseSensitivity|TestFileOpenRead|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleRelease)$'" - Starting (try 2/5) 2025/09/07 03:28:56 DEBUG : Creating backend with remote "TestPikPak:rclone-test-jetohev1xeco" 2025/09/07 03:28:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/07 03:28:58 DEBUG : Creating backend with remote "/tmp/rclone2656528364" === RUN TestFileOpenRead run.go:180: Remote "PikPak root 'rclone-test-jetohev1xeco'", Local "Local file system at /tmp/rclone2656528364", Modify Window "876000h0m0s" 2025/09/07 03:28:58 INFO : PikPak root 'rclone-test-jetohev1xeco': poll-interval is not supported by this remote 2025/09/07 03:28:58 NOTICE: PikPak root 'rclone-test-jetohev1xeco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/07 03:28:59 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/09/07 03:28:59 DEBUG : dir/file1: gcid = 3823e2b83a3ba8d1137efc1fe13e3a2e7cfb8d82 2025/09/07 03:29:01 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/09/07 03:29:01 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/09/07 03:29:01 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:29:08 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/07 03:29:08 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/09/07 03:29:14 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/07 03:29:14 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/09/07 03:29:20 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/07 03:29:20 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/09/07 03:29:27 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/07 03:29:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/09/07 03:29:33 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/07 03:29:33 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/07 03:29:39 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/07 03:29:40 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/07 03:29:42 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/07 03:29:42 DEBUG : dir/file1: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/09/07 03:29:42 DEBUG : WaitForWriters: timeout=30s 2025/09/07 03:29:42 DEBUG : dir: Looking for writers 2025/09/07 03:29:42 DEBUG : file1: reading active writers 2025/09/07 03:29:42 DEBUG : Looking for writers 2025/09/07 03:29:42 DEBUG : dir: reading active writers 2025/09/07 03:29:42 DEBUG : >WaitForWriters: 2025/09/07 03:29:43 DEBUG : pacer: Reducing sleep to 843.75ms 2025/09/07 03:29:44 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/09/07 03:29:45 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/09/07 03:29:46 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/09/07 03:29:46 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/09/07 03:29:46 DEBUG : dir: Rmdir: contains trashed file: "file1" 2025/09/07 03:29:47 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/09/07 03:29:47 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/09/07 03:29:48 DEBUG : pacer: Reducing sleep to 112.627028ms --- PASS: TestFileOpenRead (50.11s) === RUN TestReadFileHandleMethods run.go:180: Remote "PikPak root 'rclone-test-jetohev1xeco'", Local "Local file system at /tmp/rclone2656528364", Modify Window "876000h0m0s" 2025/09/07 03:29:48 INFO : PikPak root 'rclone-test-jetohev1xeco': poll-interval is not supported by this remote 2025/09/07 03:29:48 NOTICE: PikPak root 'rclone-test-jetohev1xeco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/07 03:29:48 DEBUG : pacer: Reducing sleep to 100ms 2025/09/07 03:29:49 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/09/07 03:29:49 DEBUG : dir/file1: gcid = 75291f3a188a427f873fb88fbc37ce5ce150d01c 2025/09/07 03:29:50 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/07 03:29:51 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/09/07 03:29:51 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/09/07 03:29:51 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/09/07 03:29:51 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:29:57 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/07 03:29:57 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/09/07 03:30:04 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/07 03:30:04 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/09/07 03:30:10 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/07 03:30:10 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/09/07 03:30:16 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/07 03:30:16 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/09/07 03:30:23 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/07 03:30:23 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/07 03:30:29 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/07 03:30:35 DEBUG : pacer: low level retry 7/10 (error no link) 2025/09/07 03:30:41 DEBUG : pacer: low level retry 8/10 (error no link) 2025/09/07 03:30:48 DEBUG : pacer: low level retry 9/10 (error no link) 2025/09/07 03:30:54 DEBUG : pacer: low level retry 10/10 (error no link) 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: can't download: no link 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/09/07 03:30:54 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:31:00 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/07 03:31:07 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/07 03:31:13 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/07 03:31:20 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/07 03:31:26 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/07 03:31:32 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/07 03:31:39 DEBUG : pacer: low level retry 7/10 (error no link) 2025/09/07 03:31:45 DEBUG : pacer: low level retry 8/10 (error no link) 2025/09/07 03:31:52 DEBUG : pacer: low level retry 9/10 (error no link) 2025/09/07 03:31:58 DEBUG : pacer: low level retry 10/10 (error no link) 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: can't download: no link 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/09/07 03:31:58 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:32:04 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/07 03:32:11 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/07 03:32:12 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/09/07 03:32:12 DEBUG : Config file has changed externally - reloading 2025/09/07 03:32:18 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/07 03:32:24 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/07 03:32:31 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/07 03:32:37 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/07 03:32:43 DEBUG : pacer: low level retry 7/10 (error no link) 2025/09/07 03:32:50 DEBUG : pacer: low level retry 8/10 (error no link) 2025/09/07 03:32:56 DEBUG : pacer: low level retry 9/10 (error no link) 2025/09/07 03:33:02 DEBUG : pacer: low level retry 10/10 (error no link) 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:"can't download: no link", err:fserrors.wrappedRetryError{error:(*errors.errorString)(0xc0003c31f0)}}) Test: TestReadFileHandleMethods 2025/09/07 03:33:02 DEBUG : WaitForWriters: timeout=30s 2025/09/07 03:33:02 DEBUG : dir: Looking for writers 2025/09/07 03:33:02 DEBUG : file1: reading active writers 2025/09/07 03:33:02 DEBUG : Looking for writers 2025/09/07 03:33:02 DEBUG : dir: reading active writers 2025/09/07 03:33:02 DEBUG : >WaitForWriters: 2025/09/07 03:33:02 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/07 03:33:04 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/07 03:33:06 DEBUG : pacer: Reducing sleep to 843.75ms 2025/09/07 03:33:07 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/09/07 03:33:08 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/09/07 03:33:08 DEBUG : dir: Rmdir: contains trashed file: "file1" 2025/09/07 03:33:08 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/09/07 03:33:09 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/09/07 03:33:10 DEBUG : pacer: Reducing sleep to 200.225829ms --- FAIL: TestReadFileHandleMethods (201.96s) === RUN TestReadFileHandleFlush run.go:180: Remote "PikPak root 'rclone-test-jetohev1xeco'", Local "Local file system at /tmp/rclone2656528364", Modify Window "876000h0m0s" 2025/09/07 03:33:10 INFO : PikPak root 'rclone-test-jetohev1xeco': poll-interval is not supported by this remote 2025/09/07 03:33:10 NOTICE: PikPak root 'rclone-test-jetohev1xeco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/07 03:33:10 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/09/07 03:33:10 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/09/07 03:33:10 DEBUG : pacer: Reducing sleep to 100ms 2025/09/07 03:33:10 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/09/07 03:33:10 DEBUG : dir/file1: gcid = 75291f3a188a427f873fb88fbc37ce5ce150d01c 2025/09/07 03:33:12 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/07 03:33:13 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/09/07 03:33:13 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/09/07 03:33:13 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/09/07 03:33:13 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:33:19 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/07 03:33:19 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/09/07 03:33:26 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/07 03:33:26 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/09/07 03:33:32 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/07 03:33:32 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/09/07 03:33:38 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/07 03:33:38 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/09/07 03:33:45 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/07 03:33:45 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/07 03:33:51 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/07 03:33:52 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/07 03:33:54 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/07 03:33:54 DEBUG : dir/file1: ChunkedReader.Read at 0 length 256 chunkOffset 0 chunkSize 134217728 2025/09/07 03:33:54 DEBUG : WaitForWriters: timeout=30s 2025/09/07 03:33:54 DEBUG : dir: Looking for writers 2025/09/07 03:33:54 DEBUG : file1: reading active writers 2025/09/07 03:33:54 DEBUG : Looking for writers 2025/09/07 03:33:54 DEBUG : dir: reading active writers 2025/09/07 03:33:54 DEBUG : >WaitForWriters: 2025/09/07 03:33:55 DEBUG : pacer: Reducing sleep to 843.75ms 2025/09/07 03:33:56 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/09/07 03:33:57 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/09/07 03:33:57 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/09/07 03:33:58 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/09/07 03:33:58 DEBUG : dir: Rmdir: contains trashed file: "file1" 2025/09/07 03:33:58 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/09/07 03:33:59 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/09/07 03:33:59 DEBUG : pacer: Reducing sleep to 112.627028ms --- PASS: TestReadFileHandleFlush (49.57s) === RUN TestReadFileHandleRelease run.go:180: Remote "PikPak root 'rclone-test-jetohev1xeco'", Local "Local file system at /tmp/rclone2656528364", Modify Window "876000h0m0s" 2025/09/07 03:33:59 INFO : PikPak root 'rclone-test-jetohev1xeco': poll-interval is not supported by this remote 2025/09/07 03:33:59 NOTICE: PikPak root 'rclone-test-jetohev1xeco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/07 03:33:59 DEBUG : pacer: Reducing sleep to 100ms 2025/09/07 03:34:00 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/09/07 03:34:00 DEBUG : dir/file1: gcid = 75291f3a188a427f873fb88fbc37ce5ce150d01c 2025/09/07 03:34:02 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/07 03:34:02 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/09/07 03:34:02 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/09/07 03:34:02 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/09/07 03:34:02 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:34:05 DEBUG : dir/file1: ChunkedReader.Read at 0 length 256 chunkOffset 0 chunkSize 134217728 2025/09/07 03:34:05 DEBUG : dir/file1: ReadFileHandle.Release closing 2025/09/07 03:34:05 DEBUG : dir/file1: ReadFileHandle.Release nothing to do 2025/09/07 03:34:05 DEBUG : WaitForWriters: timeout=30s 2025/09/07 03:34:05 DEBUG : dir: Looking for writers 2025/09/07 03:34:05 DEBUG : file1: reading active writers 2025/09/07 03:34:05 DEBUG : Looking for writers 2025/09/07 03:34:05 DEBUG : dir: reading active writers 2025/09/07 03:34:05 DEBUG : >WaitForWriters: 2025/09/07 03:34:07 DEBUG : dir: Rmdir: contains trashed file: "file1" --- PASS: TestReadFileHandleRelease (8.69s) === RUN TestCaseSensitivity run.go:180: Remote "PikPak root 'rclone-test-jetohev1xeco'", Local "Local file system at /tmp/rclone2656528364", Modify Window "876000h0m0s" 2025/09/07 03:34:08 DEBUG : FiLeA: calculating gcid: failed to unwrap object from src: FiLeA 2025/09/07 03:34:08 DEBUG : FiLeA: gcid = ed1267439bbc430e2a3c002a4a39ac8869a858d5 2025/09/07 03:34:10 DEBUG : FiLeB: calculating gcid: failed to unwrap object from src: FiLeB 2025/09/07 03:34:10 DEBUG : FiLeB: gcid = c0a8962153624d521cfc28e9e9437c2a7ddddffd 2025/09/07 03:34:11 DEBUG : FilEb: calculating gcid: failed to unwrap object from src: FilEb 2025/09/07 03:34:11 DEBUG : FilEb: gcid = d9d37e3253e48da9b2bbbe117f2ddc5b8654d660 2025/09/07 03:34:12 INFO : PikPak root 'rclone-test-jetohev1xeco': poll-interval is not supported by this remote 2025/09/07 03:34:12 NOTICE: PikPak root 'rclone-test-jetohev1xeco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/07 03:34:12 INFO : PikPak root 'rclone-test-jetohev1xeco': poll-interval is not supported by this remote 2025/09/07 03:34:12 NOTICE: PikPak root 'rclone-test-jetohev1xeco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/07 03:34:12 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/07 03:34:12 DEBUG : FiLeA: Open: flags=O_RDONLY 2025/09/07 03:34:12 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2025/09/07 03:34:12 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2025/09/07 03:34:12 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:34:19 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/07 03:34:19 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/09/07 03:34:25 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/07 03:34:25 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/09/07 03:34:26 DEBUG : pacer: Reducing sleep to 300ms 2025/09/07 03:34:27 DEBUG : pacer: Reducing sleep to 225ms 2025/09/07 03:34:27 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2025/09/07 03:34:27 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/07 03:34:28 DEBUG : pacer: Reducing sleep to 168.75ms 2025/09/07 03:34:28 DEBUG : FiLeA: Open: flags=O_RDONLY 2025/09/07 03:34:28 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2025/09/07 03:34:28 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2025/09/07 03:34:28 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:34:28 DEBUG : pacer: Reducing sleep to 126.5625ms 2025/09/07 03:34:29 DEBUG : pacer: Reducing sleep to 100ms 2025/09/07 03:34:29 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2025/09/07 03:34:29 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/07 03:34:29 DEBUG : FiLeA: Open: flags=O_RDONLY 2025/09/07 03:34:29 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2025/09/07 03:34:29 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2025/09/07 03:34:29 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:34:29 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2025/09/07 03:34:29 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/07 03:34:29 DEBUG : FiLeB: Open: flags=O_RDONLY 2025/09/07 03:34:29 DEBUG : FiLeB: >Open: fd=FiLeB (r), err= 2025/09/07 03:34:29 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err= 2025/09/07 03:34:29 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:34:36 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/07 03:34:36 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/09/07 03:34:42 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/07 03:34:42 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/09/07 03:34:48 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/07 03:34:48 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/09/07 03:34:55 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/07 03:34:55 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/09/07 03:35:01 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/07 03:35:01 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/07 03:35:07 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/07 03:35:14 DEBUG : pacer: low level retry 7/10 (error no link) 2025/09/07 03:35:20 DEBUG : pacer: low level retry 8/10 (error no link) 2025/09/07 03:35:26 DEBUG : pacer: low level retry 9/10 (error no link) 2025/09/07 03:35:32 DEBUG : pacer: low level retry 10/10 (error no link) 2025/09/07 03:35:32 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/07 03:35:32 DEBUG : FilEb: Open: flags=O_RDONLY 2025/09/07 03:35:32 DEBUG : FilEb: >Open: fd=FilEb (r), err= 2025/09/07 03:35:32 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err= 2025/09/07 03:35:32 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728 2025/09/07 03:35:39 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/07 03:35:45 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/07 03:35:51 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/07 03:35:52 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/07 03:35:54 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/07 03:35:54 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 vfs_case_test.go:65: Can't test case sensitivity - this remote doesn't comply as case-sensitive 2025/09/07 03:35:54 DEBUG : WaitForWriters: timeout=30s 2025/09/07 03:35:54 DEBUG : Looking for writers 2025/09/07 03:35:54 DEBUG : FiLeB: reading active writers 2025/09/07 03:35:54 DEBUG : FilEb: reading active writers 2025/09/07 03:35:54 DEBUG : FiLeA: reading active writers 2025/09/07 03:35:54 DEBUG : >WaitForWriters: 2025/09/07 03:35:54 DEBUG : WaitForWriters: timeout=30s 2025/09/07 03:35:54 DEBUG : Looking for writers 2025/09/07 03:35:54 DEBUG : FiLeA: reading active writers 2025/09/07 03:35:54 DEBUG : FiLeB: reading active writers 2025/09/07 03:35:54 DEBUG : FilEb: reading active writers 2025/09/07 03:35:54 DEBUG : >WaitForWriters: 2025/09/07 03:35:55 DEBUG : pacer: Reducing sleep to 843.75ms 2025/09/07 03:35:56 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/09/07 03:35:57 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/09/07 03:35:58 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/09/07 03:35:59 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/09/07 03:35:59 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/09/07 03:36:00 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/09/07 03:36:00 DEBUG : pacer: Reducing sleep to 112.627028ms --- SKIP: TestCaseSensitivity (112.09s) FAIL 2025/09/07 03:36:00 DEBUG : PikPak root 'rclone-test-jetohev1xeco': Purge remote 2025/09/07 03:36:00 DEBUG : pacer: Reducing sleep to 100ms "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCaseSensitivity|TestFileOpenRead|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleRelease)$'" - Finished ERROR in 7m4.915484694s (try 2/5): exit status 1: Failed [TestReadFileHandleMethods]