"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestDirEntryModTimeInvalidation|TestFileReadAtNonZeroLength)$'" - Starting (try 5/5) 2025/04/30 18:42:12 DEBUG : Creating backend with remote "TestPikPak:rclone-test-kocaduy4hexo" 2025/04/30 18:42:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/30 18:42:13 DEBUG : Creating backend with remote "/tmp/rclone904631103" === RUN TestDirEntryModTimeInvalidation run.go:180: Remote "PikPak root 'rclone-test-kocaduy4hexo'", Local "Local file system at /tmp/rclone904631103", Modify Window "876000h0m0s" 2025/04/30 18:42:13 INFO : PikPak root 'rclone-test-kocaduy4hexo': poll-interval is not supported by this remote 2025/04/30 18:42:13 NOTICE: PikPak root 'rclone-test-kocaduy4hexo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/04/30 18:42:14 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1 2025/04/30 18:42:14 DEBUG : dir/file1: gcid = 3823e2b83a3ba8d1137efc1fe13e3a2e7cfb8d82 2025/04/30 18:42:15 DEBUG : dir: forgetting directory cache 2025/04/30 18:42:15 DEBUG : : forgetting directory cache 2025/04/30 18:42:15 DEBUG : dir: forgetting directory cache 2025/04/30 18:42:15 DEBUG : dir/file2: calculating gcid: failed to unwrap object from src: dir/file2 2025/04/30 18:42:15 DEBUG : dir/file2: gcid = 8f6e1023f7530ee92b8667061641e57f5c481570 dir_test.go:687: ModTime not invalidated 2025/04/30 18:42:17 DEBUG : WaitForWriters: timeout=30s 2025/04/30 18:42:17 DEBUG : dir: Looking for writers 2025/04/30 18:42:17 DEBUG : : Looking for writers 2025/04/30 18:42:17 DEBUG : dir: reading active writers 2025/04/30 18:42:17 DEBUG : >WaitForWriters: 2025/04/30 18:42:17 DEBUG : dir: forgetting directory cache 2025/04/30 18:42:17 DEBUG : : forgetting directory cache 2025/04/30 18:42:17 DEBUG : dir: forgetting directory cache 2025/04/30 18:42:19 DEBUG : dir: Rmdir: contains trashed file: "file2" 2025/04/30 18:42:19 DEBUG : dir: Rmdir: contains trashed file: "file1" --- FAIL: TestDirEntryModTimeInvalidation (7.63s) === RUN TestFileReadAtNonZeroLength run.go:180: Remote "PikPak root 'rclone-test-kocaduy4hexo'", Local "Local file system at /tmp/rclone904631103", Modify Window "876000h0m0s" 2025/04/30 18:42:20 INFO : PikPak root 'rclone-test-kocaduy4hexo': poll-interval is not supported by this remote 2025/04/30 18:42:20 NOTICE: PikPak root 'rclone-test-kocaduy4hexo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/04/30 18:42:20 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/04/30 18:42:21 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/04/30 18:42:21 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/04/30 18:42:21 DEBUG : file1: >Open: fd=file1 (w), err= 2025/04/30 18:42:21 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/04/30 18:42:21 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/04/30 18:42:21 DEBUG : PikPak root 'rclone-test-kocaduy4hexo': File to upload is small (100 bytes), uploading instead of streaming 2025/04/30 18:42:21 DEBUG : file1: calculating gcid: failed to unwrap object from src: file1 2025/04/30 18:42:21 DEBUG : file1: gcid = c46d0324667c2199c8704dbd1113e82ad707f5fe 2025/04/30 18:42:23 DEBUG : file1: Dst hash empty - aborting Src hash check 2025/04/30 18:42:23 DEBUG : file1: Size of src and dst objects identical 2025/04/30 18:42:23 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/04/30 18:42:23 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2025/04/30 18:42:23 DEBUG : file1: Open: flags=O_RDONLY 2025/04/30 18:42:23 DEBUG : file1: >Open: fd=file1 (r), err= 2025/04/30 18:42:23 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2025/04/30 18:42:23 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2025/04/30 18:42:23 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 18:42:23 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/04/30 18:42:23 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 18:42:23 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/04/30 18:42:24 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 18:42:24 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/04/30 18:42:24 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 18:42:24 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/04/30 18:42:25 DEBUG : pacer: low level retry 5/10 (error no link) 2025/04/30 18:42:25 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/04/30 18:42:26 DEBUG : pacer: low level retry 6/10 (error no link) 2025/04/30 18:42:28 DEBUG : pacer: low level retry 7/10 (error no link) 2025/04/30 18:42:30 DEBUG : pacer: low level retry 8/10 (error no link) 2025/04/30 18:42:32 DEBUG : pacer: low level retry 9/10 (error no link) 2025/04/30 18:42:34 DEBUG : pacer: low level retry 10/10 (error no link) 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: can't download: no link 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{0x77, 0x61, 0x7a, 0x69, 0x73, 0x61, 0x62, 0x39, 0x7a, 0x61, 0x68, 0x69, 0x67, 0x65, 0x66, 0x34, 0x79, 0x69, 0x64, 0x69, 0x63, 0x75, 0x78, 0x31, 0x64, 0x69, 0x71, 0x61, 0x76, 0x61, 0x66, 0x34, 0x79, 0x65, 0x76, 0x6f, 0x77, 0x6f, 0x76, 0x33, 0x71, 0x6f, 0x77, 0x75, 0x71, 0x65, 0x71, 0x36, 0x79, 0x6f, 0x7a, 0x69, 0x79, 0x61, 0x70, 0x35, 0x73, 0x69, 0x6b, 0x69, 0x6d, 0x75, 0x72, 0x31, 0x6b, 0x65, 0x78, 0x69, 0x78, 0x6f, 0x73, 0x39, 0x76, 0x65, 0x76, 0x6f, 0x66, 0x69, 0x79, 0x39, 0x74, 0x61, 0x79, 0x65, 0x6b, 0x61, 0x77, 0x33, 0x6b, 0x61, 0x7a, 0x69, 0x66, 0x6f, 0x68, 0x39, 0x62, 0x6f, 0x74, 0x75} actual : []byte{} Diff: --- Expected +++ Actual @@ -1,9 +1,2 @@ -([]uint8) (len=100) { - 00000000 77 61 7a 69 73 61 62 39 7a 61 68 69 67 65 66 34 |wazisab9zahigef4| - 00000010 79 69 64 69 63 75 78 31 64 69 71 61 76 61 66 34 |yidicux1diqavaf4| - 00000020 79 65 76 6f 77 6f 76 33 71 6f 77 75 71 65 71 36 |yevowov3qowuqeq6| - 00000030 79 6f 7a 69 79 61 70 35 73 69 6b 69 6d 75 72 31 |yoziyap5sikimur1| - 00000040 6b 65 78 69 78 6f 73 39 76 65 76 6f 66 69 79 39 |kexixos9vevofiy9| - 00000050 74 61 79 65 6b 61 77 33 6b 61 7a 69 66 6f 68 39 |tayekaw3kazifoh9| - 00000060 62 6f 74 75 |botu| +([]uint8) { } Test: TestFileReadAtNonZeroLength 2025/04/30 18:42:34 DEBUG : WaitForWriters: timeout=30s 2025/04/30 18:42:34 DEBUG : : Looking for writers 2025/04/30 18:42:34 DEBUG : file1: reading active writers 2025/04/30 18:42:34 DEBUG : >WaitForWriters: 2025/04/30 18:42:36 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 18:42:38 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 18:42:40 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 18:42:41 DEBUG : pacer: Reducing sleep to 632.8125ms --- FAIL: TestFileReadAtNonZeroLength (20.68s) FAIL 2025/04/30 18:42:41 DEBUG : PikPak root 'rclone-test-kocaduy4hexo': Purge remote 2025/04/30 18:42:42 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/04/30 18:42:43 DEBUG : pacer: Reducing sleep to 355.957031ms "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestDirEntryModTimeInvalidation|TestFileReadAtNonZeroLength)$'" - Finished ERROR in 30.907644631s (try 5/5): exit status 1: Failed [TestDirEntryModTimeInvalidation TestFileReadAtNonZeroLength]