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