"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestFileReadAtNonZeroLength|TestReadFileHandleMethods|TestReadFileHandleReadAt)$'" - Starting (try 3/5) 2025/09/14 03:35:56 DEBUG : Creating backend with remote "TestPikPak:rclone-test-majogen5zosu" 2025/09/14 03:35:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/14 03:35:57 DEBUG : Creating backend with remote "/tmp/rclone314811227" === RUN TestReadFileHandleMethods run.go:180: Remote "PikPak root 'rclone-test-majogen5zosu'", Local "Local file system at /tmp/rclone314811227", Modify Window "876000h0m0s" 2025/09/14 03:35:57 INFO : PikPak root 'rclone-test-majogen5zosu': poll-interval is not supported by this remote 2025/09/14 03:35:57 NOTICE: PikPak root 'rclone-test-majogen5zosu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/14 03:35:59 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/09/14 03:35:59 DEBUG : dir/file1: gcid = 75291f3a188a427f873fb88fbc37ce5ce150d01c 2025/09/14 03:36:01 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/14 03:36:02 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/09/14 03:36:02 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/09/14 03:36:02 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/09/14 03:36:02 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/14 03:36:08 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/14 03:36:08 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/09/14 03:36:14 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/14 03:36:14 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/09/14 03:36:21 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/14 03:36:21 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/09/14 03:36:27 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/14 03:36:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/09/14 03:36:33 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/14 03:36:33 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/14 03:36:40 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/14 03:36:46 DEBUG : pacer: low level retry 7/10 (error no link) 2025/09/14 03:36:53 DEBUG : pacer: low level retry 8/10 (error no link) 2025/09/14 03:36:59 DEBUG : pacer: low level retry 9/10 (error no link) 2025/09/14 03:37:05 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/14 03:37:05 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/14 03:37:10 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/14 03:37:11 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/14 03:37:14 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/14 03:37:14 DEBUG : dir/file1: ChunkedReader.Read at 0 length 256 chunkOffset 0 chunkSize 134217728 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 : "0123456789abcdef" Diff: --- Expected +++ Actual @@ -1 +1 @@ -123456789abcdef +0123456789abcdef Test: TestReadFileHandleMethods 2025/09/14 03:37:14 DEBUG : WaitForWriters: timeout=30s 2025/09/14 03:37:14 DEBUG : dir: Looking for writers 2025/09/14 03:37:14 DEBUG : file1: reading active writers 2025/09/14 03:37:14 DEBUG : Looking for writers 2025/09/14 03:37:14 DEBUG : dir: reading active writers 2025/09/14 03:37:14 DEBUG : >WaitForWriters: 2025/09/14 03:37:14 DEBUG : pacer: Reducing sleep to 843.75ms 2025/09/14 03:37:15 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/09/14 03:37:16 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/09/14 03:37:17 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/09/14 03:37:17 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/09/14 03:37:17 DEBUG : dir: Rmdir: contains trashed file: "file1" 2025/09/14 03:37:18 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/09/14 03:37:19 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/09/14 03:37:19 DEBUG : pacer: Reducing sleep to 112.627028ms --- FAIL: TestReadFileHandleMethods (82.08s) === RUN TestReadFileHandleReadAt run.go:180: Remote "PikPak root 'rclone-test-majogen5zosu'", Local "Local file system at /tmp/rclone314811227", Modify Window "876000h0m0s" 2025/09/14 03:37:19 INFO : PikPak root 'rclone-test-majogen5zosu': poll-interval is not supported by this remote 2025/09/14 03:37:19 NOTICE: PikPak root 'rclone-test-majogen5zosu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/14 03:37:20 DEBUG : pacer: Reducing sleep to 100ms 2025/09/14 03:37:20 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/09/14 03:37:20 DEBUG : dir/file1: gcid = 75291f3a188a427f873fb88fbc37ce5ce150d01c 2025/09/14 03:37:22 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/09/14 03:37:23 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/09/14 03:37:23 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/09/14 03:37:23 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/09/14 03:37:23 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/09/14 03:37:29 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/14 03:37:29 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/09/14 03:37:36 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/14 03:37:36 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/09/14 03:37:42 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/14 03:37:42 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/09/14 03:37:48 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/14 03:37:48 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/09/14 03:37:55 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/14 03:37:55 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/14 03:38:01 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/14 03:38:07 DEBUG : pacer: low level retry 7/10 (error no link) 2025/09/14 03:38:13 DEBUG : pacer: low level retry 8/10 (error no link) 2025/09/14 03:38:20 DEBUG : pacer: low level retry 9/10 (error no link) 2025/09/14 03:38:26 DEBUG : pacer: low level retry 10/10 (error no link) read_test.go:132: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:132 Error: Received unexpected error: can't download: no link Test: TestReadFileHandleReadAt 2025/09/14 03:38:26 DEBUG : WaitForWriters: timeout=30s 2025/09/14 03:38:26 DEBUG : dir: Looking for writers 2025/09/14 03:38:26 DEBUG : file1: reading active writers 2025/09/14 03:38:26 DEBUG : Looking for writers 2025/09/14 03:38:26 DEBUG : dir: reading active writers 2025/09/14 03:38:26 DEBUG : >WaitForWriters: 2025/09/14 03:38:26 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/14 03:38:28 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/14 03:38:30 DEBUG : pacer: Reducing sleep to 843.75ms 2025/09/14 03:38:31 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/09/14 03:38:32 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/09/14 03:38:32 DEBUG : dir: Rmdir: contains trashed file: "file1" 2025/09/14 03:38:33 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/09/14 03:38:33 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/09/14 03:38:34 DEBUG : pacer: Reducing sleep to 200.225829ms --- FAIL: TestReadFileHandleReadAt (74.20s) === RUN TestFileReadAtNonZeroLength run.go:180: Remote "PikPak root 'rclone-test-majogen5zosu'", Local "Local file system at /tmp/rclone314811227", Modify Window "876000h0m0s" 2025/09/14 03:38:34 INFO : PikPak root 'rclone-test-majogen5zosu': poll-interval is not supported by this remote 2025/09/14 03:38:34 NOTICE: PikPak root 'rclone-test-majogen5zosu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/14 03:38:34 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/09/14 03:38:34 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/09/14 03:38:34 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/09/14 03:38:34 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/09/14 03:38:34 DEBUG : file1: >Open: fd=file1 (w), err= 2025/09/14 03:38:34 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/09/14 03:38:34 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/09/14 03:38:34 DEBUG : PikPak root 'rclone-test-majogen5zosu': File to upload is small (100 bytes), uploading instead of streaming 2025/09/14 03:38:34 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/09/14 03:38:34 DEBUG : file1: calculating gcid: failed to unwrap object from src: file1 2025/09/14 03:38:34 DEBUG : file1: gcid = 66618bd0dcdc7a9b9aed96cd7d1fbcab85347edd 2025/09/14 03:38:35 DEBUG : pacer: Reducing sleep to 100ms 2025/09/14 03:38:37 DEBUG : file1: Dst hash empty - aborting Src hash check 2025/09/14 03:38:37 DEBUG : file1: Size of src and dst objects identical 2025/09/14 03:38:37 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/09/14 03:38:37 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2025/09/14 03:38:37 DEBUG : file1: Open: flags=O_RDONLY 2025/09/14 03:38:37 DEBUG : file1: >Open: fd=file1 (r), err= 2025/09/14 03:38:37 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2025/09/14 03:38:37 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2025/09/14 03:38:42 DEBUG : pacer: low level retry 1/10 (error no link) 2025/09/14 03:38:42 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/09/14 03:38:48 DEBUG : pacer: low level retry 2/10 (error no link) 2025/09/14 03:38:48 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/09/14 03:38:53 DEBUG : pacer: low level retry 3/10 (error no link) 2025/09/14 03:38:53 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/09/14 03:38:58 DEBUG : pacer: low level retry 4/10 (error no link) 2025/09/14 03:38:58 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/09/14 03:39:03 DEBUG : pacer: low level retry 5/10 (error no link) 2025/09/14 03:39:03 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/14 03:39:09 DEBUG : pacer: low level retry 6/10 (error no link) 2025/09/14 03:39:15 DEBUG : pacer: low level retry 7/10 (error no link) 2025/09/14 03:39:21 DEBUG : pacer: low level retry 8/10 (error no link) 2025/09/14 03:39:28 DEBUG : pacer: low level retry 9/10 (error no link) 2025/09/14 03:39:29 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/14 03:39:31 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/14 03:39:31 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2025/09/14 03:39:31 DEBUG : WaitForWriters: timeout=30s 2025/09/14 03:39:31 DEBUG : Looking for writers 2025/09/14 03:39:31 DEBUG : file1: reading active writers 2025/09/14 03:39:31 DEBUG : >WaitForWriters: 2025/09/14 03:39:31 DEBUG : pacer: Reducing sleep to 843.75ms 2025/09/14 03:39:33 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/09/14 03:39:33 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/09/14 03:39:34 DEBUG : pacer: Reducing sleep to 355.957031ms --- PASS: TestFileReadAtNonZeroLength (60.53s) FAIL 2025/09/14 03:39:34 DEBUG : PikPak root 'rclone-test-majogen5zosu': Purge remote 2025/09/14 03:39:35 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/09/14 03:39:35 DEBUG : pacer: Reducing sleep to 200.225829ms "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestFileReadAtNonZeroLength|TestReadFileHandleMethods|TestReadFileHandleReadAt)$'" - Finished ERROR in 3m39.423332261s (try 3/5): exit status 1: Failed [TestReadFileHandleMethods TestReadFileHandleReadAt]