"./vfs.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestDirMetadataExtension|TestDirMkdirSub)$'" - Starting (try 2/5) 2025/05/23 05:12:30 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-petecas9nome" 2025/05/23 05:12:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/23 05:12:30 DEBUG : Creating backend with remote "/tmp/rclone1977462682" === RUN TestDirMkdirSub run.go:180: Remote "HiDrive root 'rclone-test-petecas9nome'", Local "Local file system at /tmp/rclone1977462682", Modify Window "1s" 2025/05/23 05:12:30 INFO : HiDrive root 'rclone-test-petecas9nome': poll-interval is not supported by this remote 2025/05/23 05:12:30 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name dir 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name file1 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name dir 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name file1 2025/05/23 05:12:31 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/05/23 05:12:31 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub" 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name dir 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name file1 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name sub 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name subsub 2025/05/23 05:12:31 DEBUG : WaitForWriters: timeout=30s 2025/05/23 05:12:31 DEBUG : dir/sub/subsub: Looking for writers 2025/05/23 05:12:31 DEBUG : dir/sub: Looking for writers 2025/05/23 05:12:31 DEBUG : subsub: reading active writers 2025/05/23 05:12:31 DEBUG : dir: Looking for writers 2025/05/23 05:12:31 DEBUG : file1: reading active writers 2025/05/23 05:12:31 DEBUG : sub: reading active writers 2025/05/23 05:12:31 DEBUG : : Looking for writers 2025/05/23 05:12:31 DEBUG : dir: reading active writers 2025/05/23 05:12:31 DEBUG : >WaitForWriters: 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name dir 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name file1 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name sub 2025/05/23 05:12:31 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name subsub --- PASS: TestDirMkdirSub (1.20s) === RUN TestDirMetadataExtension run.go:180: Remote "HiDrive root 'rclone-test-petecas9nome'", Local "Local file system at /tmp/rclone1977462682", Modify Window "1s" 2025/05/23 05:12:31 INFO : HiDrive root 'rclone-test-petecas9nome': poll-interval is not supported by this remote 2025/05/23 05:12:32 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name dir 2025/05/23 05:12:32 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name file1 2025/05/23 05:12:32 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name dir 2025/05/23 05:12:32 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name file1 2025/05/23 05:12:32 DEBUG : dir: Added virtual directory entry vAddFile: "file1.metadata" 2025/05/23 05:12:32 DEBUG : dir/file1.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/05/23 05:12:32 DEBUG : dir/file1.metadata: Open: flags=O_RDONLY 2025/05/23 05:12:32 DEBUG : dir/file1.metadata: >Open: fd=dir/file1.metadata (r), err= 2025/05/23 05:12:32 DEBUG : dir/file1.metadata: >OpenFile: fd=dir/file1.metadata (r), err= 2025/05/23 05:12:32 DEBUG : dir/file1.metadata: ChunkedReader.openRange at 0 length 134217728 2025/05/23 05:12:32 DEBUG : dir/file1.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/05/23 05:12:32 DEBUG : : Added virtual directory entry vAddFile: "dir.metadata" 2025/05/23 05:12:32 DEBUG : dir.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/05/23 05:12:32 DEBUG : dir.metadata: Open: flags=O_RDONLY 2025/05/23 05:12:32 DEBUG : dir.metadata: >Open: fd=dir.metadata (r), err= 2025/05/23 05:12:32 DEBUG : dir.metadata: >OpenFile: fd=dir.metadata (r), err= 2025/05/23 05:12:32 DEBUG : dir.metadata: ChunkedReader.openRange at 0 length 134217728 2025/05/23 05:12:32 DEBUG : dir.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/05/23 05:12:32 DEBUG : WaitForWriters: timeout=30s 2025/05/23 05:12:32 DEBUG : dir: Looking for writers 2025/05/23 05:12:32 DEBUG : file1: reading active writers 2025/05/23 05:12:32 DEBUG : file1.metadata: reading active writers 2025/05/23 05:12:32 DEBUG : : Looking for writers 2025/05/23 05:12:32 DEBUG : dir.metadata: reading active writers 2025/05/23 05:12:32 DEBUG : dir: reading active writers 2025/05/23 05:12:32 DEBUG : >WaitForWriters: 2025/05/23 05:12:32 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name dir 2025/05/23 05:12:32 DEBUG : HiDrive root 'rclone-test-petecas9nome': found directory-element with name file1 --- PASS: TestDirMetadataExtension (0.55s) PASS 2025/05/23 05:12:32 DEBUG : HiDrive root 'rclone-test-petecas9nome': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestDirMetadataExtension|TestDirMkdirSub)$'" - Finished OK in 2.030298552s (try 2/5)