"./vfs.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestDirCreate|TestDirMetadataExtension)$'" - Starting (try 2/5) 2025/07/20 03:47:03 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-varixac2kizi" 2025/07/20 03:47:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/20 03:47:03 DEBUG : Creating backend with remote "/tmp/rclone1130948925" === RUN TestDirCreate run.go:180: Remote "HiDrive root 'rclone-test-varixac2kizi'", Local "Local file system at /tmp/rclone1130948925", Modify Window "1s" 2025/07/20 03:47:03 INFO : HiDrive root 'rclone-test-varixac2kizi': poll-interval is not supported by this remote 2025/07/20 03:47:04 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name dir 2025/07/20 03:47:04 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name file1 2025/07/20 03:47:04 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name dir 2025/07/20 03:47:04 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name file1 2025/07/20 03:47:04 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/07/20 03:47:04 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/07/20 03:47:04 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/07/20 03:47:04 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/07/20 03:47:04 DEBUG : HiDrive root 'rclone-test-varixac2kizi': File to upload is small (5 bytes), uploading instead of streaming 2025/07/20 03:47:04 DEBUG : dir/potato: hidrive = 44c6e36e32a5029692f2f32e7844d8f52832db8a OK 2025/07/20 03:47:04 DEBUG : dir/potato: Size and hidrive of src and dst objects identical 2025/07/20 03:47:04 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/07/20 03:47:04 DEBUG : WaitForWriters: timeout=30s 2025/07/20 03:47:04 DEBUG : dir: Looking for writers 2025/07/20 03:47:04 DEBUG : file1: reading active writers 2025/07/20 03:47:04 DEBUG : potato: reading active writers 2025/07/20 03:47:04 DEBUG : Looking for writers 2025/07/20 03:47:04 DEBUG : dir: reading active writers 2025/07/20 03:47:04 DEBUG : >WaitForWriters: 2025/07/20 03:47:04 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name dir 2025/07/20 03:47:04 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name file1 2025/07/20 03:47:04 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name potato --- PASS: TestDirCreate (0.97s) === RUN TestDirMetadataExtension run.go:180: Remote "HiDrive root 'rclone-test-varixac2kizi'", Local "Local file system at /tmp/rclone1130948925", Modify Window "1s" 2025/07/20 03:47:04 INFO : HiDrive root 'rclone-test-varixac2kizi': poll-interval is not supported by this remote 2025/07/20 03:47:05 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name dir 2025/07/20 03:47:05 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name file1 2025/07/20 03:47:05 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name dir 2025/07/20 03:47:05 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name file1 2025/07/20 03:47:05 DEBUG : dir: Added virtual directory entry vAddFile: "file1.metadata" 2025/07/20 03:47:05 DEBUG : dir/file1.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/07/20 03:47:05 DEBUG : dir/file1.metadata: Open: flags=O_RDONLY 2025/07/20 03:47:05 DEBUG : dir/file1.metadata: >Open: fd=dir/file1.metadata (r), err= 2025/07/20 03:47:05 DEBUG : dir/file1.metadata: >OpenFile: fd=dir/file1.metadata (r), err= 2025/07/20 03:47:05 DEBUG : dir/file1.metadata: ChunkedReader.openRange at 0 length 134217728 2025/07/20 03:47:05 DEBUG : dir/file1.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/07/20 03:47:05 DEBUG : Added virtual directory entry vAddFile: "dir.metadata" 2025/07/20 03:47:05 DEBUG : dir.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/07/20 03:47:05 DEBUG : dir.metadata: Open: flags=O_RDONLY 2025/07/20 03:47:05 DEBUG : dir.metadata: >Open: fd=dir.metadata (r), err= 2025/07/20 03:47:05 DEBUG : dir.metadata: >OpenFile: fd=dir.metadata (r), err= 2025/07/20 03:47:05 DEBUG : dir.metadata: ChunkedReader.openRange at 0 length 134217728 2025/07/20 03:47:05 DEBUG : dir.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/07/20 03:47:05 DEBUG : WaitForWriters: timeout=30s 2025/07/20 03:47:05 DEBUG : dir: Looking for writers 2025/07/20 03:47:05 DEBUG : file1: reading active writers 2025/07/20 03:47:05 DEBUG : file1.metadata: reading active writers 2025/07/20 03:47:05 DEBUG : Looking for writers 2025/07/20 03:47:05 DEBUG : dir: reading active writers 2025/07/20 03:47:05 DEBUG : dir.metadata: reading active writers 2025/07/20 03:47:05 DEBUG : >WaitForWriters: 2025/07/20 03:47:05 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name dir 2025/07/20 03:47:05 DEBUG : HiDrive root 'rclone-test-varixac2kizi': found directory-element with name file1 --- PASS: TestDirMetadataExtension (0.71s) PASS 2025/07/20 03:47:05 DEBUG : HiDrive root 'rclone-test-varixac2kizi': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestDirCreate|TestDirMetadataExtension)$'" - Finished OK in 2.009661037s (try 2/5)