"./vfs.test -test.v -test.timeout 1h0m0s -remote TestHdfs: -verbose -test.run '^(TestCaseSensitivity|TestDirHandleMethods|TestDirHandleReaddir|TestDirReadDirAll|TestDirSetModTime|TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestRcGetVFS|TestReadFileHandleSeek|TestVFSNew|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters)$'" - Starting (try 2/5) 2022/04/15 05:08:01 DEBUG : Creating backend with remote "TestHdfs:rclone-test-hozafil6sivijaf5pufujiv9" 2022/04/15 05:08:01 DEBUG : Setting type="hdfs" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_TYPE 2022/04/15 05:08:01 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE 2022/04/15 05:08:01 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME 2022/04/15 05:08:01 DEBUG : TestHdfs: detected overridden config - adding "{cNNQW}" suffix to name 2022/04/15 05:08:01 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE 2022/04/15 05:08:01 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME 2022/04/15 05:08:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/15 05:08:01 DEBUG : Creating backend with remote "/tmp/rclone2311928660" === RUN TestDirHandleMethods run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:01 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:01 DEBUG : hdfs://127.0.0.1:8020: mkdir [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:01 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestDirHandleMethods (0.03s) === RUN TestDirHandleReaddir run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir/file3] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir/subdir: Looking for writers 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : subdir: reading active writers 2022/04/15 05:08:02 DEBUG : file1: reading active writers 2022/04/15 05:08:02 DEBUG : file2: reading active writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir/file3] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestDirHandleReaddir (0.12s) === RUN TestDirSetModTime run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestDirSetModTime (0.02s) === RUN TestDirReadDirAll run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir/file3] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir] === RUN TestDirReadDirAll/Virtual 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vDel: "file2" 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/virtualFile] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/virtualDir/testFile] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: purge [/rclone-test-hozafil6sivijaf5pufujiv9/dir/subdir] 2022/04/15 05:08:02 DEBUG : dir: invalidating directory cache 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2022/04/15 05:08:02 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2022/04/15 05:08:02 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2022/04/15 05:08:02 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vDel: "file1" 2022/04/15 05:08:02 DEBUG : dir: invalidating directory cache 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2" 2022/04/15 05:08:02 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2022/04/15 05:08:02 DEBUG : : forgetting directory cache 2022/04/15 05:08:02 DEBUG : dir: forgetting directory cache 2022/04/15 05:08:02 DEBUG : dir/virtualDir: forgetting directory cache 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir/virtualDir: Looking for writers 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : virtualFile: reading active writers 2022/04/15 05:08:02 DEBUG : virtualDir: reading active writers 2022/04/15 05:08:02 DEBUG : file1: reading active writers 2022/04/15 05:08:02 DEBUG : virtualFile2: reading active writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/virtualFile] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir/virtualDir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/virtualDir/testFile] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir/virtualDir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestDirReadDirAll (0.10s) --- PASS: TestDirReadDirAll/Virtual (0.04s) === RUN TestRcGetVFS run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestRcGetVFS (0.00s) === RUN TestReadFileHandleSeek run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : dir/file1: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2022/04/15 05:08:02 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : dir/file1: waiting for in-sequence read to 5 for 20ms 2022/04/15 05:08:02 DEBUG : dir/file1: aborting in-sequence read wait, off=5 2022/04/15 05:08:02 DEBUG : dir/file1: failed to wait for in-sequence read to 5 2022/04/15 05:08:02 DEBUG : dir/file1: ReadFileHandle.seek from 1 to 5 (fs.RangeSeeker) 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.RangeSeek from 1 to 5 length -1 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 5 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : dir/file1: ReadFileHandle.seek from 6 to 3 (fs.RangeSeeker) 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.RangeSeek from 6 to 3 length -1 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 3 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.openRange at 3 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : dir/file1: ReadFileHandle.seek from 4 to 13 (fs.RangeSeeker) 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.RangeSeek from 4 to 13 length -1 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 13 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : dir/file1: ChunkedReader.openRange at 13 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : file1: reading active writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestReadFileHandleSeek (0.08s) === RUN TestCaseSensitivity run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeB] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/FilEb] 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : FiLeA: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : FiLeA: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeA: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeB: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeB: >Open: fd=FiLeB (r), err= 2022/04/15 05:08:02 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err= 2022/04/15 05:08:02 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeB] 2022/04/15 05:08:02 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FilEb: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FilEb: >Open: fd=FilEb (r), err= 2022/04/15 05:08:02 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err= 2022/04/15 05:08:02 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FilEb] 2022/04/15 05:08:02 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeA: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : fileA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeA: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : fileA: >OpenFile: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : filea: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeA: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : filea: >OpenFile: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : FILEA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeA: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FILEA: >OpenFile: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeB: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeB: >Open: fd=FiLeB (r), err= 2022/04/15 05:08:02 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err= 2022/04/15 05:08:02 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeB] 2022/04/15 05:08:02 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FilEb: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FilEb: >Open: fd=FilEb (r), err= 2022/04/15 05:08:02 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err= 2022/04/15 05:08:02 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FilEb] 2022/04/15 05:08:02 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : fileb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 ERROR : /: Dir.Stat error: duplicate filename "fileb" detected with --vfs-case-insensitive set 2022/04/15 05:08:02 DEBUG : fileb: >OpenFile: fd=, err=duplicate filename "fileb" detected with --vfs-case-insensitive set 2022/04/15 05:08:02 DEBUG : FILEB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 ERROR : /: Dir.Stat error: duplicate filename "FILEB" detected with --vfs-case-insensitive set 2022/04/15 05:08:02 DEBUG : FILEB: >OpenFile: fd=, err=duplicate filename "FILEB" detected with --vfs-case-insensitive set 2022/04/15 05:08:02 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeA: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : fileA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : fileA: >OpenFile: fd=, err=file does not exist 2022/04/15 05:08:02 DEBUG : filea: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : filea: >OpenFile: fd=, err=file does not exist 2022/04/15 05:08:02 DEBUG : FILEA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FILEA: >OpenFile: fd=, err=file does not exist 2022/04/15 05:08:02 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FiLeB: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FiLeB: >Open: fd=FiLeB (r), err= 2022/04/15 05:08:02 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err= 2022/04/15 05:08:02 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeB] 2022/04/15 05:08:02 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FilEb: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : FilEb: >Open: fd=FilEb (r), err= 2022/04/15 05:08:02 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err= 2022/04/15 05:08:02 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/FilEb] 2022/04/15 05:08:02 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:02 DEBUG : fileb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : fileb: >OpenFile: fd=, err=file does not exist 2022/04/15 05:08:02 DEBUG : FILEB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : FILEB: >OpenFile: fd=, err=file does not exist 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : FiLeA: reading active writers 2022/04/15 05:08:02 DEBUG : FiLeB: reading active writers 2022/04/15 05:08:02 DEBUG : FilEb: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : FiLeA: reading active writers 2022/04/15 05:08:02 DEBUG : FiLeB: reading active writers 2022/04/15 05:08:02 DEBUG : FilEb: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeA] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/FiLeB] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/FilEb] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestCaseSensitivity (0.12s) === RUN TestVFSNew run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: Re-using VFS from active cache 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestVFSNew (0.00s) === RUN TestVFSStat run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : file2: reading active writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : file1: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestVFSStat (0.06s) === RUN TestVFSStatParent run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : file1: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestVFSStatParent (0.07s) === RUN TestVFSOpenFile run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : file1: Open: flags=O_RDONLY 2022/04/15 05:08:02 DEBUG : file1: >Open: fd=file1 (r), err= 2022/04/15 05:08:02 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2022/04/15 05:08:02 DEBUG : dir: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : dir: >OpenFile: fd=dir/ (r), err= 2022/04/15 05:08:02 DEBUG : dir/new_file.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : dir/new_file.txt: >OpenFile: fd=, err=file does not exist 2022/04/15 05:08:02 DEBUG : dir/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : dir/new_file.txt: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2022/04/15 05:08:02 DEBUG : dir/new_file.txt: >Open: fd=dir/new_file.txt (w), err= 2022/04/15 05:08:02 DEBUG : dir/new_file.txt: >OpenFile: fd=dir/new_file.txt (w), err= 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (0 bytes), uploading instead of streaming 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/new_file.txt] 2022/04/15 05:08:02 INFO : dir/new_file.txt: Copied (new) 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2022/04/15 05:08:02 DEBUG : not found/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : not found/new_file.txt: >OpenFile: fd=, err=file does not exist 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : new_file.txt: reading active writers 2022/04/15 05:08:02 DEBUG : file2: reading active writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : file1: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/dir/new_file.txt] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestVFSOpenFile (0.05s) === RUN TestVFSRename run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rename [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file2] to [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] 2022/04/15 05:08:02 INFO : dir/file2: Moved (server-side) to: dir/file1 2022/04/15 05:08:02 DEBUG : dir/file1: Updating file with dir/file1 0xc0007d4f00 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vDel: "file2" 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-hozafil6sivijaf5pufujiv9/file0] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rename [/rclone-test-hozafil6sivijaf5pufujiv9/dir/file1] to [/rclone-test-hozafil6sivijaf5pufujiv9/file0] 2022/04/15 05:08:02 INFO : dir/file1: Moved (server-side) to: file0 2022/04/15 05:08:02 DEBUG : file0: Updating file with file0 0xc0007d4f00 2022/04/15 05:08:02 DEBUG : dir: Added virtual directory entry vDel: "file1" 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file0" 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : dir: Looking for writers 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : dir: reading active writers 2022/04/15 05:08:02 DEBUG : file0: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file0] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-hozafil6sivijaf5pufujiv9/dir] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestVFSRename (0.04s) === RUN TestWriteFileHandleMethods run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2022/04/15 05:08:02 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2022/04/15 05:08:02 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (5 bytes), uploading instead of streaming 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 INFO : file1: Copied (new) 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:02 ERROR : file1: WriteFileHandle: Can't open for write without O_TRUNC on existing file without --vfs-cache-mode >= writes 2022/04/15 05:08:02 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (0 bytes), uploading instead of streaming 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 INFO : file1: Copied (new) 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (7 bytes), uploading instead of streaming 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 INFO : file1: Copied (new) 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:02 DEBUG : : Looking for writers 2022/04/15 05:08:02 DEBUG : file1: reading active writers 2022/04/15 05:08:02 DEBUG : >WaitForWriters: 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestWriteFileHandleMethods (0.05s) === RUN TestWriteFileHandleWriteAt run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:02 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:02 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:02 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:02 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:02 DEBUG : file1: waiting for in-sequence write to 100 for 1s 2022/04/15 05:08:03 DEBUG : file1: aborting in-sequence write wait, off=100 2022/04/15 05:08:03 DEBUG : file1: failed to wait for in-sequence write to 100 2022/04/15 05:08:03 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (11 bytes), uploading instead of streaming 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 INFO : file1: Copied (new) 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 ERROR : file1: WriteFileHandle.Write: error: Bad file descriptor 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:03 DEBUG : : Looking for writers 2022/04/15 05:08:03 DEBUG : file1: reading active writers 2022/04/15 05:08:03 DEBUG : >WaitForWriters: 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestWriteFileHandleWriteAt (1.02s) === RUN TestWriteFileHandleFlush run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:03 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:03 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:03 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:03 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (5 bytes), uploading instead of streaming 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 INFO : file1: Copied (new) 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : file1: WriteFileHandle.Flush nothing to do 2022/04/15 05:08:03 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:03 DEBUG : : Looking for writers 2022/04/15 05:08:03 DEBUG : file1: reading active writers 2022/04/15 05:08:03 DEBUG : >WaitForWriters: 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestWriteFileHandleFlush (0.01s) === RUN TestWriteFileModTimeWithOpenWriters run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:03 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:03 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:03 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/time_test] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-hozafil6sivijaf5pufujiv9/time_test] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/time_test] 2022/04/15 05:08:03 DEBUG : Can set mod time: true 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (2 bytes), uploading instead of streaming 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 INFO : file1: Copied (new) 2022/04/15 05:08:03 DEBUG : file1: Applied pending mod time 2012-11-18 17:32:31 +0000 UTC OK 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:03 DEBUG : : Looking for writers 2022/04/15 05:08:03 DEBUG : file1: reading active writers 2022/04/15 05:08:03 DEBUG : >WaitForWriters: 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestWriteFileModTimeWithOpenWriters (0.06s) === RUN TestFileReadAtZeroLength run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:03 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:03 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:03 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (0 bytes), uploading instead of streaming 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 INFO : file1: Copied (new) 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2022/04/15 05:08:03 DEBUG : file1: Open: flags=O_RDONLY 2022/04/15 05:08:03 DEBUG : file1: >Open: fd=file1 (r), err= 2022/04/15 05:08:03 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2022/04/15 05:08:03 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:03 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:03 DEBUG : : Looking for writers 2022/04/15 05:08:03 DEBUG : file1: reading active writers 2022/04/15 05:08:03 DEBUG : >WaitForWriters: 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestFileReadAtZeroLength (0.01s) === RUN TestFileReadAtNonZeroLength run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone2311928660", Modify Window "1s" 2022/04/15 05:08:03 INFO : hdfs://127.0.0.1:8020: poll-interval is not supported by this remote 2022/04/15 05:08:03 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : file1: >Open: fd=file1 (w), err= 2022/04/15 05:08:03 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: File to upload is small (100 bytes), uploading instead of streaming 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 INFO : file1: Copied (new) 2022/04/15 05:08:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/04/15 05:08:03 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2022/04/15 05:08:03 DEBUG : file1: Open: flags=O_RDONLY 2022/04/15 05:08:03 DEBUG : file1: >Open: fd=file1 (r), err= 2022/04/15 05:08:03 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2022/04/15 05:08:03 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: open [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2022/04/15 05:08:03 DEBUG : WaitForWriters: timeout=30s 2022/04/15 05:08:03 DEBUG : : Looking for writers 2022/04/15 05:08:03 DEBUG : file1: reading active writers 2022/04/15 05:08:03 DEBUG : >WaitForWriters: 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-hozafil6sivijaf5pufujiv9/file1] 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-hozafil6sivijaf5pufujiv9] --- PASS: TestFileReadAtNonZeroLength (0.03s) PASS 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: Purge remote 2022/04/15 05:08:03 DEBUG : hdfs://127.0.0.1:8020: purge [/rclone-test-hozafil6sivijaf5pufujiv9] "./vfs.test -test.v -test.timeout 1h0m0s -remote TestHdfs: -verbose -test.run '^(TestCaseSensitivity|TestDirHandleMethods|TestDirHandleReaddir|TestDirReadDirAll|TestDirSetModTime|TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestRcGetVFS|TestReadFileHandleSeek|TestVFSNew|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters)$'" - Finished OK in 1.905155466s (try 2/5)