"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestFileOpenRead|TestReadFileHandleRelease|TestReadFileHandleSeek)$'" - Starting (try 2/5) 2025/08/24 04:23:33 DEBUG : Creating backend with remote "TestPikPak:rclone-test-wapulab8sava" 2025/08/24 04:23:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/24 04:23:35 DEBUG : Creating backend with remote "/tmp/rclone4233207045" === RUN TestFileOpenRead run.go:180: Remote "PikPak root 'rclone-test-wapulab8sava'", Local "Local file system at /tmp/rclone4233207045", Modify Window "876000h0m0s" 2025/08/24 04:23:35 INFO : PikPak root 'rclone-test-wapulab8sava': poll-interval is not supported by this remote 2025/08/24 04:23:35 NOTICE: PikPak root 'rclone-test-wapulab8sava': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/08/24 04:23:36 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/08/24 04:23:36 DEBUG : dir/file1: gcid = 3823e2b83a3ba8d1137efc1fe13e3a2e7cfb8d82 2025/08/24 04:23:38 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/08/24 04:23:38 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/08/24 04:23:38 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/08/24 04:23:45 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:23:45 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/08/24 04:23:51 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:23:51 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/08/24 04:23:57 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:23:57 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/08/24 04:24:04 DEBUG : pacer: low level retry 4/10 (error no link) 2025/08/24 04:24:04 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/08/24 04:24:10 DEBUG : pacer: low level retry 5/10 (error no link) 2025/08/24 04:24:10 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/08/24 04:24:16 DEBUG : pacer: low level retry 6/10 (error no link) 2025/08/24 04:24:23 DEBUG : pacer: low level retry 7/10 (error no link) 2025/08/24 04:24:29 DEBUG : pacer: low level retry 8/10 (error no link) 2025/08/24 04:24:35 DEBUG : pacer: low level retry 9/10 (error no link) 2025/08/24 04:24:42 DEBUG : pacer: low level retry 10/10 (error no link) 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: can't download: no link Test: TestFileOpenRead 2025/08/24 04:24:42 DEBUG : WaitForWriters: timeout=30s 2025/08/24 04:24:42 DEBUG : dir: Looking for writers 2025/08/24 04:24:42 DEBUG : file1: reading active writers 2025/08/24 04:24:42 DEBUG : Looking for writers 2025/08/24 04:24:42 DEBUG : dir: reading active writers 2025/08/24 04:24:42 DEBUG : >WaitForWriters: 2025/08/24 04:24:42 DEBUG : pacer: Reducing sleep to 1.5s 2025/08/24 04:24:44 DEBUG : pacer: Reducing sleep to 1.125s 2025/08/24 04:24:45 DEBUG : pacer: Reducing sleep to 843.75ms 2025/08/24 04:24:46 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/08/24 04:24:47 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/08/24 04:24:47 DEBUG : dir: Rmdir: contains trashed file: "file1" 2025/08/24 04:24:48 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/08/24 04:24:49 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/08/24 04:24:49 DEBUG : pacer: Reducing sleep to 200.225829ms --- FAIL: TestFileOpenRead (74.46s) === RUN TestReadFileHandleSeek run.go:180: Remote "PikPak root 'rclone-test-wapulab8sava'", Local "Local file system at /tmp/rclone4233207045", Modify Window "876000h0m0s" 2025/08/24 04:24:49 INFO : PikPak root 'rclone-test-wapulab8sava': poll-interval is not supported by this remote 2025/08/24 04:24:49 NOTICE: PikPak root 'rclone-test-wapulab8sava': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/08/24 04:24:49 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/08/24 04:24:50 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/08/24 04:24:50 DEBUG : pacer: Reducing sleep to 100ms 2025/08/24 04:24:50 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/08/24 04:24:50 DEBUG : dir/file1: gcid = 75291f3a188a427f873fb88fbc37ce5ce150d01c 2025/08/24 04:24:52 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/08/24 04:24:52 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/08/24 04:24:52 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/08/24 04:24:52 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/08/24 04:24:52 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/08/24 04:24:59 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:24:59 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/08/24 04:25:05 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:25:05 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/08/24 04:25:11 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:25:11 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/08/24 04:25:12 DEBUG : pacer: Reducing sleep to 600ms 2025/08/24 04:25:13 DEBUG : pacer: Reducing sleep to 450ms 2025/08/24 04:25:13 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728 2025/08/24 04:25:13 DEBUG : dir/file1: waiting for in-sequence read to 5 for 20ms 2025/08/24 04:25:13 DEBUG : dir/file1: aborting in-sequence read wait, off=5 2025/08/24 04:25:13 DEBUG : dir/file1: failed to wait for in-sequence read to 5 2025/08/24 04:25:13 DEBUG : dir/file1: ReadFileHandle.seek from 1 to 5 (fs.RangeSeeker) 2025/08/24 04:25:13 DEBUG : dir/file1: ChunkedReader.RangeSeek from 1 to 5 length -1 2025/08/24 04:25:13 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 5 chunkSize 134217728 2025/08/24 04:25:13 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2025/08/24 04:25:14 DEBUG : pacer: Reducing sleep to 337.5ms 2025/08/24 04:25:14 DEBUG : dir/file1: ReadFileHandle.seek from 6 to 3 (fs.RangeSeeker) 2025/08/24 04:25:14 DEBUG : dir/file1: ChunkedReader.RangeSeek from 6 to 3 length -1 2025/08/24 04:25:14 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 3 chunkSize 134217728 2025/08/24 04:25:14 DEBUG : dir/file1: ChunkedReader.openRange at 3 length 134217728 2025/08/24 04:25:16 DEBUG : pacer: Reducing sleep to 253.125ms 2025/08/24 04:25:16 DEBUG : dir/file1: ReadFileHandle.seek from 4 to 13 (fs.RangeSeeker) 2025/08/24 04:25:16 DEBUG : dir/file1: ChunkedReader.RangeSeek from 4 to 13 length -1 2025/08/24 04:25:16 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 13 chunkSize 134217728 2025/08/24 04:25:16 DEBUG : dir/file1: ChunkedReader.openRange at 13 length 134217728 2025/08/24 04:25:17 DEBUG : pacer: Reducing sleep to 189.84375ms 2025/08/24 04:25:17 DEBUG : WaitForWriters: timeout=30s 2025/08/24 04:25:17 DEBUG : dir: Looking for writers 2025/08/24 04:25:17 DEBUG : file1: reading active writers 2025/08/24 04:25:17 DEBUG : Looking for writers 2025/08/24 04:25:17 DEBUG : dir: reading active writers 2025/08/24 04:25:17 DEBUG : >WaitForWriters: 2025/08/24 04:25:17 DEBUG : pacer: Reducing sleep to 142.382812ms 2025/08/24 04:25:17 DEBUG : pacer: Reducing sleep to 106.787109ms 2025/08/24 04:25:18 DEBUG : pacer: Reducing sleep to 100ms 2025/08/24 04:25:19 DEBUG : dir: Rmdir: contains trashed file: "file1" --- PASS: TestReadFileHandleSeek (30.97s) === RUN TestReadFileHandleRelease run.go:180: Remote "PikPak root 'rclone-test-wapulab8sava'", Local "Local file system at /tmp/rclone4233207045", Modify Window "876000h0m0s" 2025/08/24 04:25:20 INFO : PikPak root 'rclone-test-wapulab8sava': poll-interval is not supported by this remote 2025/08/24 04:25:20 NOTICE: PikPak root 'rclone-test-wapulab8sava': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/08/24 04:25:21 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/08/24 04:25:21 DEBUG : dir/file1: gcid = 75291f3a188a427f873fb88fbc37ce5ce150d01c 2025/08/24 04:25:23 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/08/24 04:25:23 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/08/24 04:25:23 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/08/24 04:25:23 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/08/24 04:25:23 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/08/24 04:25:30 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:25:30 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/08/24 04:25:36 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:25:36 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/08/24 04:25:36 DEBUG : pacer: Reducing sleep to 300ms 2025/08/24 04:25:38 DEBUG : pacer: Reducing sleep to 225ms 2025/08/24 04:25:38 DEBUG : dir/file1: ChunkedReader.Read at 0 length 256 chunkOffset 0 chunkSize 134217728 2025/08/24 04:25:38 DEBUG : dir/file1: ReadFileHandle.Release closing 2025/08/24 04:25:38 DEBUG : dir/file1: ReadFileHandle.Release nothing to do 2025/08/24 04:25:38 DEBUG : WaitForWriters: timeout=30s 2025/08/24 04:25:38 DEBUG : dir: Looking for writers 2025/08/24 04:25:38 DEBUG : file1: reading active writers 2025/08/24 04:25:38 DEBUG : Looking for writers 2025/08/24 04:25:38 DEBUG : dir: reading active writers 2025/08/24 04:25:38 DEBUG : >WaitForWriters: 2025/08/24 04:25:38 DEBUG : pacer: Reducing sleep to 168.75ms 2025/08/24 04:25:38 DEBUG : pacer: Reducing sleep to 126.5625ms 2025/08/24 04:25:38 DEBUG : pacer: Reducing sleep to 100ms 2025/08/24 04:25:39 DEBUG : dir: Rmdir: contains trashed file: "file1" --- PASS: TestReadFileHandleRelease (20.70s) FAIL 2025/08/24 04:25:41 DEBUG : PikPak root 'rclone-test-wapulab8sava': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestFileOpenRead|TestReadFileHandleRelease|TestReadFileHandleSeek)$'" - Finished ERROR in 2m8.665126385s (try 2/5): exit status 1: Failed [TestFileOpenRead]