"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestFileReadAtNonZeroLength$'" - Starting (try 2/5) 2025/02/06 04:20:20 DEBUG : Creating backend with remote "TestPikPak:rclone-test-ludakis3mohi" 2025/02/06 04:20:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/06 04:20:22 DEBUG : Creating backend with remote "/tmp/rclone386862229" === RUN TestFileReadAtNonZeroLength run.go:180: Remote "PikPak root 'rclone-test-ludakis3mohi'", Local "Local file system at /tmp/rclone386862229", Modify Window "876000h0m0s" 2025/02/06 04:20:22 INFO : PikPak root 'rclone-test-ludakis3mohi': poll-interval is not supported by this remote 2025/02/06 04:20:22 NOTICE: PikPak root 'rclone-test-ludakis3mohi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/02/06 04:20:22 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/02/06 04:20:22 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/02/06 04:20:22 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/06 04:20:22 DEBUG : file1: >Open: fd=file1 (w), err= 2025/02/06 04:20:22 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/02/06 04:20:22 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/06 04:20:22 DEBUG : PikPak root 'rclone-test-ludakis3mohi': File to upload is small (100 bytes), uploading instead of streaming 2025/02/06 04:20:23 DEBUG : file1: calculating gcid: failed to unwrap object from src: file1 2025/02/06 04:20:23 DEBUG : file1: gcid = d4b0b8ce4c28228d6130fd33bbf73833d240e433 2025/02/06 04:20:25 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/06 04:20:25 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/02/06 04:20:25 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/06 04:20:25 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/02/06 04:20:26 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/06 04:20:26 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/02/06 04:20:26 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/06 04:20:26 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/02/06 04:20:27 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/06 04:20:27 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/02/06 04:20:28 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/06 04:20:30 DEBUG : pacer: Reducing sleep to 1.5s 2025/02/06 04:20:30 DEBUG : file1: Dst hash empty - aborting Src hash check 2025/02/06 04:20:30 DEBUG : file1: Size of src and dst objects identical 2025/02/06 04:20:30 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/06 04:20:30 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2025/02/06 04:20:30 DEBUG : file1: Open: flags=O_RDONLY 2025/02/06 04:20:30 DEBUG : file1: >Open: fd=file1 (r), err= 2025/02/06 04:20:30 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2025/02/06 04:20:30 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2025/02/06 04:20:32 DEBUG : pacer: Reducing sleep to 1.125s 2025/02/06 04:20:35 DEBUG : pacer: Reducing sleep to 843.75ms 2025/02/06 04:20:35 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2025/02/06 04:20:35 DEBUG : WaitForWriters: timeout=30s 2025/02/06 04:20:35 DEBUG : : Looking for writers 2025/02/06 04:20:35 DEBUG : file1: reading active writers 2025/02/06 04:20:35 DEBUG : >WaitForWriters: 2025/02/06 04:20:35 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/02/06 04:20:36 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/02/06 04:20:37 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/02/06 04:20:37 DEBUG : pacer: Reducing sleep to 266.967773ms --- PASS: TestFileReadAtNonZeroLength (15.57s) PASS 2025/02/06 04:20:37 DEBUG : PikPak root 'rclone-test-ludakis3mohi': Purge remote 2025/02/06 04:20:37 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/02/06 04:20:38 DEBUG : pacer: Reducing sleep to 150.169371ms "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestFileReadAtNonZeroLength$'" - Finished OK in 18.097900058s (try 2/5)