"./vfs.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestDirMetadataExtension|TestFileOpenRead)$'" - Starting (try 2/5) 2026/10/10 01:11:38 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-bupipow7vuxa" 2026/10/10 01:11:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/10 01:11:38 DEBUG : Creating backend with remote "/tmp/rclone3719179230" === RUN TestDirMetadataExtension run.go:198: Remote "HiDrive root 'rclone-test-bupipow7vuxa'", Local "Local file system at /tmp/rclone3719179230", Modify Window "1s" 2026/10/10 01:11:38 INFO : HiDrive root 'rclone-test-bupipow7vuxa': poll-interval is not supported by this remote 2026/10/10 01:11:38 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': found directory-element with name dir 2026/10/10 01:11:38 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': found directory-element with name file1 2026/10/10 01:11:38 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': found directory-element with name dir 2026/10/10 01:11:38 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': found directory-element with name file1 2026/10/10 01:11:38 DEBUG : dir: Added virtual directory entry vAddFile: "file1.metadata" 2026/10/10 01:11:38 DEBUG : dir/file1.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2026/10/10 01:11:38 DEBUG : dir/file1.metadata: Open: flags=O_RDONLY 2026/10/10 01:11:38 DEBUG : dir/file1.metadata: >Open: fd=dir/file1.metadata (r), err= 2026/10/10 01:11:38 DEBUG : dir/file1.metadata: >OpenFile: fd=dir/file1.metadata (r), err= 2026/10/10 01:11:38 DEBUG : dir/file1.metadata: ChunkedReader.openRange at 0 length 134217728 2026/10/10 01:11:38 DEBUG : dir/file1.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2026/10/10 01:11:38 DEBUG : Added virtual directory entry vAddFile: "dir.metadata" 2026/10/10 01:11:38 DEBUG : dir.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2026/10/10 01:11:38 DEBUG : dir.metadata: Open: flags=O_RDONLY 2026/10/10 01:11:38 DEBUG : dir.metadata: >Open: fd=dir.metadata (r), err= 2026/10/10 01:11:38 DEBUG : dir.metadata: >OpenFile: fd=dir.metadata (r), err= 2026/10/10 01:11:38 DEBUG : dir.metadata: ChunkedReader.openRange at 0 length 134217728 2026/10/10 01:11:38 DEBUG : dir.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2026/10/10 01:11:38 DEBUG : dir/newfile: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rw-rw-rw- 2026/10/10 01:11:38 DEBUG : dir/newfile: Open: flags=O_WRONLY|O_CREATE 2026/10/10 01:11:38 DEBUG : dir: Added virtual directory entry vAddFile: "newfile" 2026/10/10 01:11:38 DEBUG : dir/newfile: >Open: fd=dir/newfile (w), err= 2026/10/10 01:11:38 DEBUG : dir/newfile: >OpenFile: fd=dir/newfile (w), err= 2026/10/10 01:11:38 DEBUG : dir: Added virtual directory entry vAddFile: "newfile.metadata" 2026/10/10 01:11:38 DEBUG : dir/newfile.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2026/10/10 01:11:38 DEBUG : dir/newfile.metadata: Open: flags=O_RDONLY 2026/10/10 01:11:38 DEBUG : dir/newfile.metadata: >Open: fd=dir/newfile.metadata (r), err= 2026/10/10 01:11:38 DEBUG : dir/newfile.metadata: >OpenFile: fd=dir/newfile.metadata (r), err= 2026/10/10 01:11:38 DEBUG : dir/newfile.metadata: ChunkedReader.openRange at 0 length 134217728 2026/10/10 01:11:38 DEBUG : dir/newfile.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2026/10/10 01:11:38 DEBUG : dir: Added virtual directory entry vAddFile: "newfile" 2026/10/10 01:11:38 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': File to upload is small (0 bytes), uploading instead of streaming 2026/10/10 01:11:38 DEBUG : dir/newfile: size = 0 OK 2026/10/10 01:11:38 DEBUG : dir/newfile: hidrive = 0000000000000000000000000000000000000000 OK 2026/10/10 01:11:38 DEBUG : dir/newfile: Size and hidrive of src and dst objects identical 2026/10/10 01:11:38 DEBUG : dir: Added virtual directory entry vAddFile: "newfile" 2026/10/10 01:11:38 DEBUG : WaitForWriters: timeout=30s 2026/10/10 01:11:38 DEBUG : dir: Looking for writers 2026/10/10 01:11:38 DEBUG : newfile: reading active writers 2026/10/10 01:11:38 DEBUG : newfile.metadata: reading active writers 2026/10/10 01:11:38 DEBUG : file1: reading active writers 2026/10/10 01:11:38 DEBUG : file1.metadata: reading active writers 2026/10/10 01:11:38 DEBUG : Looking for writers 2026/10/10 01:11:38 DEBUG : dir: reading active writers 2026/10/10 01:11:38 DEBUG : dir.metadata: reading active writers 2026/10/10 01:11:38 DEBUG : >WaitForWriters: 2026/10/10 01:11:38 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': found directory-element with name dir 2026/10/10 01:11:38 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': found directory-element with name file1 2026/10/10 01:11:38 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': found directory-element with name newfile --- PASS: TestDirMetadataExtension (0.78s) === RUN TestFileOpenRead run.go:198: Remote "HiDrive root 'rclone-test-bupipow7vuxa'", Local "Local file system at /tmp/rclone3719179230", Modify Window "1s" 2026/10/10 01:11:39 INFO : HiDrive root 'rclone-test-bupipow7vuxa': poll-interval is not supported by this remote run.go:303: Failed to put "dir/file1" to "HiDrive root 'rclone-test-bupipow7vuxa'": directory not found 2026/10/10 01:11:39 DEBUG : WaitForWriters: timeout=30s 2026/10/10 01:11:39 DEBUG : Looking for writers 2026/10/10 01:11:39 DEBUG : >WaitForWriters: 2026/10/10 01:11:39 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': found directory-element with name dir --- FAIL: TestFileOpenRead (0.40s) FAIL 2026/10/10 01:11:39 DEBUG : HiDrive root 'rclone-test-bupipow7vuxa': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestDirMetadataExtension|TestFileOpenRead)$'" - Finished ERROR in 1.543078589s (try 2/5): exit status 1: Failed [TestFileOpenRead]