"./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestDirRemoveAll|TestZipDirsInRoot|TestZipManySubDirs)$'" - Starting (try 3/5)
2026/09/07 04:24:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-totimit0jide"
2026/09/07 04:24:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/07 04:24:11 DEBUG : Creating backend with remote "/tmp/rclone1124641335"
=== RUN   TestDirRemoveAll
    run.go:198: Remote "drime root 'rclone-test-totimit0jide'", Local "Local file system at /tmp/rclone1124641335", Modify Window "876000h0m0s"
2026/09/07 04:24:11 INFO  : drime root 'rclone-test-totimit0jide': poll-interval is not supported by this remote
2026/09/07 04:24:17 DEBUG : dir/file1: Remove: 
2026/09/07 04:24:18 DEBUG : dir: Added virtual directory entry vDel: "file1"
2026/09/07 04:24:18 DEBUG : dir/file1: >Remove: err=<nil>
2026/09/07 04:24:18 DEBUG : Added virtual directory entry vDel: "dir"
2026/09/07 04:24:18 DEBUG : WaitForWriters: timeout=30s
2026/09/07 04:24:18 DEBUG : Looking for writers
2026/09/07 04:24:18 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemoveAll (7.72s)
=== RUN   TestZipManySubDirs
    run.go:198: Remote "drime root 'rclone-test-totimit0jide'", Local "Local file system at /tmp/rclone1124641335", Modify Window "876000h0m0s"
2026/09/07 04:24:19 INFO  : drime root 'rclone-test-totimit0jide': poll-interval is not supported by this remote
2026/09/07 04:24:22 DEBUG : a/b/c/deep.txt: Open: flags=O_RDONLY
2026/09/07 04:24:22 DEBUG : a/b/c/deep.txt: >Open: fd=a/b/c/deep.txt (r), err=<nil>
2026/09/07 04:24:22 DEBUG : a/b/c/deep.txt: Set virtual modtime to 2026-09-07 04:24:21 +0000 UTC
2026/09/07 04:24:22 DEBUG : a/b/c/deep.txt: ChunkedReader.openRange at 0 length 134217728
2026/09/07 04:24:22 DEBUG : a/b/c/deep.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/09/07 04:24:22 DEBUG : a/b/mid.txt: Open: flags=O_RDONLY
2026/09/07 04:24:22 DEBUG : a/b/mid.txt: >Open: fd=a/b/mid.txt (r), err=<nil>
2026/09/07 04:24:22 DEBUG : a/b/mid.txt: Set virtual modtime to 2026-09-07 04:24:20 +0000 UTC
2026/09/07 04:24:22 DEBUG : a/b/mid.txt: ChunkedReader.openRange at 0 length 134217728
2026/09/07 04:24:22 DEBUG : a/b/mid.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/09/07 04:24:22 DEBUG : a/top.txt: Open: flags=O_RDONLY
2026/09/07 04:24:22 DEBUG : a/top.txt: >Open: fd=a/top.txt (r), err=<nil>
2026/09/07 04:24:22 DEBUG : a/top.txt: Set virtual modtime to 2026-09-07 04:24:19 +0000 UTC
2026/09/07 04:24:22 DEBUG : a/top.txt: ChunkedReader.openRange at 0 length 134217728
2026/09/07 04:24:23 DEBUG : a/top.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/09/07 04:24:23 DEBUG : WaitForWriters: timeout=30s
2026/09/07 04:24:23 DEBUG : a/b/c: Looking for writers
2026/09/07 04:24:23 DEBUG : deep.txt: reading active writers
2026/09/07 04:24:23 DEBUG : a/b: Looking for writers
2026/09/07 04:24:23 DEBUG : c: reading active writers
2026/09/07 04:24:23 DEBUG : mid.txt: reading active writers
2026/09/07 04:24:23 DEBUG : a: Looking for writers
2026/09/07 04:24:23 DEBUG : b: reading active writers
2026/09/07 04:24:23 DEBUG : top.txt: reading active writers
2026/09/07 04:24:23 DEBUG : Looking for writers
2026/09/07 04:24:23 DEBUG : a: reading active writers
2026/09/07 04:24:23 DEBUG : >WaitForWriters: 
    run.go:186: removing dir "a/b/c" failed - try 1/5: sleeping 1s: directory not empty
--- PASS: TestZipManySubDirs (7.63s)
=== RUN   TestZipDirsInRoot
    run.go:198: Remote "drime root 'rclone-test-totimit0jide'", Local "Local file system at /tmp/rclone1124641335", Modify Window "876000h0m0s"
2026/09/07 04:24:26 INFO  : drime root 'rclone-test-totimit0jide': poll-interval is not supported by this remote
2026/09/07 04:24:34 DEBUG : dir1/a.txt: Open: flags=O_RDONLY
2026/09/07 04:24:34 DEBUG : dir1/a.txt: >Open: fd=dir1/a.txt (r), err=<nil>
2026/09/07 04:24:34 DEBUG : dir1/a.txt: Set virtual modtime to 2026-09-07 04:24:28 +0000 UTC
2026/09/07 04:24:34 DEBUG : dir1/a.txt: ChunkedReader.openRange at 0 length 134217728
2026/09/07 04:24:35 DEBUG : dir1/a.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/09/07 04:24:35 DEBUG : dir2/b.txt: Open: flags=O_RDONLY
2026/09/07 04:24:35 DEBUG : dir2/b.txt: >Open: fd=dir2/b.txt (r), err=<nil>
2026/09/07 04:24:35 DEBUG : dir2/b.txt: Set virtual modtime to 2026-09-07 04:24:32 +0000 UTC
2026/09/07 04:24:35 DEBUG : dir2/b.txt: ChunkedReader.openRange at 0 length 134217728
2026/09/07 04:24:35 DEBUG : dir2/b.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/09/07 04:24:35 DEBUG : dir3/c.txt: Open: flags=O_RDONLY
2026/09/07 04:24:35 DEBUG : dir3/c.txt: >Open: fd=dir3/c.txt (r), err=<nil>
2026/09/07 04:24:35 DEBUG : dir3/c.txt: Set virtual modtime to 2026-09-07 04:24:33 +0000 UTC
2026/09/07 04:24:35 DEBUG : dir3/c.txt: ChunkedReader.openRange at 0 length 134217728
2026/09/07 04:24:36 DEBUG : dir3/c.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/09/07 04:24:36 DEBUG : WaitForWriters: timeout=30s
2026/09/07 04:24:36 DEBUG : dir1: Looking for writers
2026/09/07 04:24:36 DEBUG : a.txt: reading active writers
2026/09/07 04:24:36 DEBUG : dir2: Looking for writers
2026/09/07 04:24:36 DEBUG : b.txt: reading active writers
2026/09/07 04:24:36 DEBUG : dir3: Looking for writers
2026/09/07 04:24:36 DEBUG : c.txt: reading active writers
2026/09/07 04:24:36 DEBUG : Looking for writers
2026/09/07 04:24:36 DEBUG : dir1: reading active writers
2026/09/07 04:24:36 DEBUG : dir2: reading active writers
2026/09/07 04:24:36 DEBUG : dir3: reading active writers
2026/09/07 04:24:36 DEBUG : >WaitForWriters: 
--- PASS: TestZipDirsInRoot (12.92s)
PASS
2026/09/07 04:24:39 DEBUG : drime root 'rclone-test-totimit0jide': Purge remote
"./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestDirRemoveAll|TestZipDirsInRoot|TestZipManySubDirs)$'" - Finished OK in 31.140420974s (try 3/5)