"./vfs.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestDirMkdirSub|TestVFSOpenFile)$'" - Starting (try 2/5) 2026/08/30 01:02:08 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-riheqib3cusu" 2026/08/30 01:02:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/30 01:02:08 DEBUG : Creating backend with remote "/tmp/rclone1155627986" === RUN TestDirMkdirSub run.go:198: Remote "HiDrive root 'rclone-test-riheqib3cusu'", Local "Local file system at /tmp/rclone1155627986", Modify Window "1s" 2026/08/30 01:02:08 INFO : HiDrive root 'rclone-test-riheqib3cusu': poll-interval is not supported by this remote 2026/08/30 01:02:08 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name dir 2026/08/30 01:02:08 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file1 2026/08/30 01:02:08 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name dir 2026/08/30 01:02:08 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file1 2026/08/30 01:02:09 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2026/08/30 01:02:09 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub" 2026/08/30 01:02:09 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name dir 2026/08/30 01:02:09 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file1 2026/08/30 01:02:09 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name sub 2026/08/30 01:02:09 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name subsub 2026/08/30 01:02:09 DEBUG : WaitForWriters: timeout=30s 2026/08/30 01:02:09 DEBUG : dir/sub/subsub: Looking for writers 2026/08/30 01:02:09 DEBUG : dir/sub: Looking for writers 2026/08/30 01:02:09 DEBUG : subsub: reading active writers 2026/08/30 01:02:09 DEBUG : dir: Looking for writers 2026/08/30 01:02:09 DEBUG : file1: reading active writers 2026/08/30 01:02:09 DEBUG : sub: reading active writers 2026/08/30 01:02:09 DEBUG : Looking for writers 2026/08/30 01:02:09 DEBUG : dir: reading active writers 2026/08/30 01:02:09 DEBUG : >WaitForWriters: 2026/08/30 01:02:09 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name dir 2026/08/30 01:02:09 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file1 2026/08/30 01:02:09 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name sub 2026/08/30 01:02:09 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name subsub --- PASS: TestDirMkdirSub (1.15s) === RUN TestVFSOpenFile run.go:198: Remote "HiDrive root 'rclone-test-riheqib3cusu'", Local "Local file system at /tmp/rclone1155627986", Modify Window "1s" 2026/08/30 01:02:09 INFO : HiDrive root 'rclone-test-riheqib3cusu': poll-interval is not supported by this remote 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name dir 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file1 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file2 2026/08/30 01:02:10 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name dir 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file1 2026/08/30 01:02:10 DEBUG : file1: Open: flags=O_RDONLY 2026/08/30 01:02:10 DEBUG : file1: >Open: fd=file1 (r), err= 2026/08/30 01:02:10 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2026/08/30 01:02:10 DEBUG : dir: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/08/30 01:02:10 DEBUG : dir: >OpenFile: fd=dir/ (r), err= 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file2 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: >OpenFile: fd=, err=file does not exist 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: Open: flags=O_WRONLY|O_CREATE 2026/08/30 01:02:10 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: >Open: fd=dir/new_file.txt (w), err= 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: >OpenFile: fd=dir/new_file.txt (w), err= 2026/08/30 01:02:10 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': File to upload is small (0 bytes), uploading instead of streaming 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: size = 0 OK 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: hidrive = 0000000000000000000000000000000000000000 OK 2026/08/30 01:02:10 DEBUG : dir/new_file.txt: Size and hidrive of src and dst objects identical 2026/08/30 01:02:10 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2026/08/30 01:02:10 DEBUG : not found/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/08/30 01:02:10 DEBUG : not found/new_file.txt: >OpenFile: fd=, err=file does not exist 2026/08/30 01:02:10 DEBUG : WaitForWriters: timeout=30s 2026/08/30 01:02:10 DEBUG : dir: Looking for writers 2026/08/30 01:02:10 DEBUG : file2: reading active writers 2026/08/30 01:02:10 DEBUG : new_file.txt: reading active writers 2026/08/30 01:02:10 DEBUG : Looking for writers 2026/08/30 01:02:10 DEBUG : dir: reading active writers 2026/08/30 01:02:10 DEBUG : file1: reading active writers 2026/08/30 01:02:10 DEBUG : >WaitForWriters: 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name dir 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file1 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name file2 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': found directory-element with name new_file.txt --- PASS: TestVFSOpenFile (1.12s) PASS 2026/08/30 01:02:10 DEBUG : HiDrive root 'rclone-test-riheqib3cusu': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestDirMkdirSub|TestVFSOpenFile)$'" - Finished OK in 2.733865936s (try 2/5)