"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestDirFileOpen|TestDirHandleReaddir|TestDirRemoveAll|TestReadFileHandleRelease)$'" - Starting (try 2/5) 2025/07/09 04:09:13 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-nogixop2nepi" 2025/07/09 04:09:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/09 04:09:14 DEBUG : Creating backend with remote "/tmp/rclone1202822761" === RUN TestDirHandleReaddir run.go:180: Remote "files root 'rclone-test-nogixop2nepi'", Local "Local file system at /tmp/rclone1202822761", Modify Window "1s" 2025/07/09 04:09:14 INFO : files root 'rclone-test-nogixop2nepi': poll-interval is not supported by this remote 2025/07/09 04:09:18 DEBUG : WaitForWriters: timeout=30s 2025/07/09 04:09:18 DEBUG : dir/subdir: Looking for writers 2025/07/09 04:09:18 DEBUG : dir: Looking for writers 2025/07/09 04:09:18 DEBUG : file2: reading active writers 2025/07/09 04:09:18 DEBUG : subdir: reading active writers 2025/07/09 04:09:18 DEBUG : file1: reading active writers 2025/07/09 04:09:18 DEBUG : Looking for writers 2025/07/09 04:09:18 DEBUG : dir: reading active writers 2025/07/09 04:09:18 DEBUG : >WaitForWriters: --- PASS: TestDirHandleReaddir (5.53s) === RUN TestDirRemoveAll run.go:180: Remote "files root 'rclone-test-nogixop2nepi'", Local "Local file system at /tmp/rclone1202822761", Modify Window "1s" 2025/07/09 04:09:19 INFO : files root 'rclone-test-nogixop2nepi': poll-interval is not supported by this remote 2025/07/09 04:09:21 DEBUG : dir/file1: Remove: 2025/07/09 04:09:21 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/07/09 04:09:21 DEBUG : dir/file1: >Remove: err= 2025/07/09 04:09:21 DEBUG : Added virtual directory entry vDel: "dir" 2025/07/09 04:09:21 DEBUG : WaitForWriters: timeout=30s 2025/07/09 04:09:21 DEBUG : Looking for writers 2025/07/09 04:09:21 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveAll (2.11s) === RUN TestDirFileOpen run.go:180: Remote "files root 'rclone-test-nogixop2nepi'", Local "Local file system at /tmp/rclone1202822761", Modify Window "1s" 2025/07/09 04:09:21 INFO : files root 'rclone-test-nogixop2nepi': poll-interval is not supported by this remote 2025/07/09 04:09:23 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/07/09 04:09:23 DEBUG : dir/sub/file0: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2025/07/09 04:09:23 DEBUG : dir/sub/file0: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2025/07/09 04:09:23 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/07/09 04:09:23 DEBUG : dir/sub/file0: >Open: fd=dir/sub/file0 (w), err= 2025/07/09 04:09:23 DEBUG : dir/sub/file0: >OpenFile: fd=dir/sub/file0 (w), err= 2025/07/09 04:09:23 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/07/09 04:09:23 DEBUG : dir/sub/file2: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2025/07/09 04:09:23 DEBUG : dir/sub/file2: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2025/07/09 04:09:23 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/07/09 04:09:23 DEBUG : dir/sub/file2: >Open: fd=dir/sub/file2 (w), err= 2025/07/09 04:09:23 DEBUG : dir/sub/file2: >OpenFile: fd=dir/sub/file2 (w), err= 2025/07/09 04:09:23 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/07/09 04:09:23 DEBUG : files root 'rclone-test-nogixop2nepi': File to upload is small (12 bytes), uploading instead of streaming 2025/07/09 04:09:24 DEBUG : dir/sub/file2: Dst hash empty - aborting Src hash check 2025/07/09 04:09:24 DEBUG : dir/sub/file2: Size of src and dst objects identical 2025/07/09 04:09:24 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/07/09 04:09:24 DEBUG : forgetting directory cache 2025/07/09 04:09:24 DEBUG : dir: forgetting directory cache 2025/07/09 04:09:24 DEBUG : dir/sub: forgetting directory cache 2025/07/09 04:09:24 DEBUG : dir/sub: Removed virtual directory entry vAddFile: "file2" 2025/07/09 04:09:24 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2025/07/09 04:09:24 DEBUG : files root 'rclone-test-nogixop2nepi': File to upload is small (5 bytes), uploading instead of streaming 2025/07/09 04:09:25 DEBUG : dir/sub/file0: Dst hash empty - aborting Src hash check 2025/07/09 04:09:25 DEBUG : dir/sub/file0: Size of src and dst objects identical 2025/07/09 04:09:25 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/07/09 04:09:25 DEBUG : WaitForWriters: timeout=30s 2025/07/09 04:09:25 DEBUG : dir/sub: Looking for writers 2025/07/09 04:09:25 DEBUG : file0: reading active writers 2025/07/09 04:09:25 DEBUG : file2: reading active writers 2025/07/09 04:09:25 DEBUG : dir: Looking for writers 2025/07/09 04:09:25 DEBUG : file1: reading active writers 2025/07/09 04:09:25 DEBUG : sub: reading active writers 2025/07/09 04:09:25 DEBUG : Looking for writers 2025/07/09 04:09:25 DEBUG : dir: reading active writers 2025/07/09 04:09:25 DEBUG : >WaitForWriters: 2025/07/09 04:09:26 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-nogixop2nepi/dir not empty`) 2025/07/09 04:09:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/07/09 04:09:26 DEBUG : pacer: Reducing sleep to 15ms 2025/07/09 04:09:26 DEBUG : pacer: Reducing sleep to 11.25ms --- PASS: TestDirFileOpen (4.78s) === RUN TestReadFileHandleRelease run.go:180: Remote "files root 'rclone-test-nogixop2nepi'", Local "Local file system at /tmp/rclone1202822761", Modify Window "1s" 2025/07/09 04:09:26 INFO : files root 'rclone-test-nogixop2nepi': poll-interval is not supported by this remote 2025/07/09 04:09:27 DEBUG : pacer: Reducing sleep to 10ms 2025/07/09 04:09:27 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/07/09 04:09:27 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/07/09 04:09:27 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/07/09 04:09:27 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/07/09 04:09:27 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/07/09 04:09:28 DEBUG : dir/file1: ChunkedReader.Read at 0 length 256 chunkOffset 0 chunkSize 134217728 2025/07/09 04:09:28 DEBUG : dir/file1: ReadFileHandle.Release closing 2025/07/09 04:09:28 DEBUG : dir/file1: ReadFileHandle.Release nothing to do 2025/07/09 04:09:28 DEBUG : WaitForWriters: timeout=30s 2025/07/09 04:09:28 DEBUG : dir: Looking for writers 2025/07/09 04:09:28 DEBUG : file1: reading active writers 2025/07/09 04:09:28 DEBUG : Looking for writers 2025/07/09 04:09:28 DEBUG : dir: reading active writers 2025/07/09 04:09:28 DEBUG : >WaitForWriters: --- PASS: TestReadFileHandleRelease (2.41s) PASS 2025/07/09 04:09:28 DEBUG : files root 'rclone-test-nogixop2nepi': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestDirFileOpen|TestDirHandleReaddir|TestDirRemoveAll|TestReadFileHandleRelease)$'" - Finished OK in 15.899980306s (try 2/5)