"./vfs.test -test.v -test.timeout 1h0m0s -remote TestKoofr: -verbose -test.run '^(TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMethods|TestDirOpen|TestDirReadDirAll|TestDirSetModTime|TestDirStat|TestDirWalk)$'" - Starting (try 2/5)
2024/11/09 04:40:26 DEBUG : Creating backend with remote "TestKoofr:rclone-test-demewir0qihi"
2024/11/09 04:40:26 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/09 04:40:27 DEBUG : Creating backend with remote "/tmp/rclone3837911696"
=== RUN   TestDirHandleMethods
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:40:27 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:40:30 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:40:30 DEBUG : dir: Looking for writers
2024/11/09 04:40:30 DEBUG : : Looking for writers
2024/11/09 04:40:30 DEBUG : dir: reading active writers
2024/11/09 04:40:30 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleMethods (5.89s)
=== RUN   TestDirHandleReaddir
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:40:33 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:40:40 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:40:40 DEBUG : dir/subdir: Looking for writers
2024/11/09 04:40:40 DEBUG : dir: Looking for writers
2024/11/09 04:40:40 DEBUG : file1: reading active writers
2024/11/09 04:40:40 DEBUG : file2: reading active writers
2024/11/09 04:40:40 DEBUG : subdir: reading active writers
2024/11/09 04:40:40 DEBUG : : Looking for writers
2024/11/09 04:40:40 DEBUG : dir: reading active writers
2024/11/09 04:40:40 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleReaddir (11.98s)
=== RUN   TestDirHandleReaddirnames
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:40:45 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:40:48 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:40:48 DEBUG : dir: Looking for writers
2024/11/09 04:40:48 DEBUG : file1: reading active writers
2024/11/09 04:40:48 DEBUG : : Looking for writers
2024/11/09 04:40:48 DEBUG : dir: reading active writers
2024/11/09 04:40:48 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleReaddirnames (4.72s)
=== RUN   TestDirMethods
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:40:50 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:40:52 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:40:52 DEBUG : dir: Looking for writers
2024/11/09 04:40:52 DEBUG : : Looking for writers
2024/11/09 04:40:52 DEBUG : dir: reading active writers
2024/11/09 04:40:52 DEBUG : >WaitForWriters: 
--- PASS: TestDirMethods (4.58s)
=== RUN   TestDirForgetAll
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:40:54 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:40:58 DEBUG : dir: forgetting directory cache
2024/11/09 04:40:58 DEBUG : : forgetting directory cache
2024/11/09 04:40:58 DEBUG : dir: forgetting directory cache
2024/11/09 04:40:58 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:40:58 DEBUG : : Looking for writers
2024/11/09 04:40:58 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetAll (5.18s)
=== RUN   TestDirForgetPath
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:40:59 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:41:03 DEBUG : : ForgetPath: relativePath="dir/notfound", type=1
2024/11/09 04:41:03 DEBUG : dir: invalidating directory cache
2024/11/09 04:41:03 DEBUG : : >ForgetPath: 
2024/11/09 04:41:03 DEBUG : : ForgetPath: relativePath="dir", type=0
2024/11/09 04:41:03 DEBUG : : invalidating directory cache
2024/11/09 04:41:03 DEBUG : dir: forgetting directory cache
2024/11/09 04:41:03 DEBUG : : >ForgetPath: 
2024/11/09 04:41:03 DEBUG : : ForgetPath: relativePath="not/in/cache", type=0
2024/11/09 04:41:03 DEBUG : : >ForgetPath: 
2024/11/09 04:41:03 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:41:03 DEBUG : dir: Looking for writers
2024/11/09 04:41:03 DEBUG : : Looking for writers
2024/11/09 04:41:03 DEBUG : dir: reading active writers
2024/11/09 04:41:03 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetPath (5.97s)
=== RUN   TestDirWalk
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:41:05 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:41:19 DEBUG : : forgetting directory cache
2024/11/09 04:41:19 DEBUG : dir: forgetting directory cache
2024/11/09 04:41:20 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:41:20 DEBUG : dir: Looking for writers
2024/11/09 04:41:20 DEBUG : fil/a/b: Looking for writers
2024/11/09 04:41:20 DEBUG : fil/a: Looking for writers
2024/11/09 04:41:20 DEBUG : b: reading active writers
2024/11/09 04:41:20 DEBUG : fil: Looking for writers
2024/11/09 04:41:20 DEBUG : a: reading active writers
2024/11/09 04:41:20 DEBUG : : Looking for writers
2024/11/09 04:41:20 DEBUG : dir: reading active writers
2024/11/09 04:41:20 DEBUG : fil: reading active writers
2024/11/09 04:41:20 DEBUG : >WaitForWriters: 
--- PASS: TestDirWalk (22.80s)
=== RUN   TestDirSetModTime
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:41:28 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:41:33 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:41:33 DEBUG : dir: Looking for writers
2024/11/09 04:41:33 DEBUG : : Looking for writers
2024/11/09 04:41:33 DEBUG : dir: reading active writers
2024/11/09 04:41:33 DEBUG : >WaitForWriters: 
--- PASS: TestDirSetModTime (8.23s)
=== RUN   TestDirStat
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:41:36 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:41:41 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:41:41 DEBUG : dir: Looking for writers
2024/11/09 04:41:41 DEBUG : file1: reading active writers
2024/11/09 04:41:41 DEBUG : : Looking for writers
2024/11/09 04:41:41 DEBUG : dir: reading active writers
2024/11/09 04:41:41 DEBUG : >WaitForWriters: 
--- PASS: TestDirStat (7.19s)
=== RUN   TestDirReadDirAll
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:41:44 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
=== RUN   TestDirReadDirAll/Virtual
2024/11/09 04:41:56 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile"
2024/11/09 04:41:56 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir"
2024/11/09 04:41:56 DEBUG : dir: Added virtual directory entry vDel: "file2"
2024/11/09 04:41:56 DEBUG : dir: Added virtual directory entry vDel: "subdir"
2024/11/09 04:42:06 DEBUG : Waiting for deletions to finish
2024/11/09 04:42:07 INFO  : dir/subdir/file3: Deleted
2024/11/09 04:42:07 DEBUG : removing 1 level 2 directories
2024/11/09 04:42:07 INFO  : dir/subdir: Removing directory
2024/11/09 04:42:08 DEBUG : dir: invalidating directory cache
2024/11/09 04:42:09 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir"
2024/11/09 04:42:09 DEBUG : dir: Removed virtual directory entry vDel: "file2"
2024/11/09 04:42:09 DEBUG : dir: Removed virtual directory entry vDel: "subdir"
2024/11/09 04:42:09 DEBUG : dir/file1: Reset virtual modtime
2024/11/09 04:42:09 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile"
2024/11/09 04:42:09 DEBUG : dir/virtualFile: Reset virtual modtime
2024/11/09 04:42:09 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2"
2024/11/09 04:42:09 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2"
2024/11/09 04:42:09 DEBUG : dir: Added virtual directory entry vDel: "file1"
2024/11/09 04:42:09 DEBUG : dir: invalidating directory cache
2024/11/09 04:42:09 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2"
2024/11/09 04:42:09 DEBUG : dir: Removed virtual directory entry vDel: "file1"
2024/11/09 04:42:09 DEBUG : dir/virtualFile: Reset virtual modtime
2024/11/09 04:42:09 DEBUG : : forgetting directory cache
2024/11/09 04:42:09 DEBUG : dir: forgetting directory cache
2024/11/09 04:42:09 DEBUG : dir/virtualDir: forgetting directory cache
2024/11/09 04:42:09 DEBUG : dir/file1: Reset virtual modtime
2024/11/09 04:42:09 DEBUG : dir/virtualFile: Reset virtual modtime
2024/11/09 04:42:09 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:42:09 DEBUG : dir/virtualDir: Looking for writers
2024/11/09 04:42:09 DEBUG : dir: Looking for writers
2024/11/09 04:42:09 DEBUG : file1: reading active writers
2024/11/09 04:42:09 DEBUG : virtualFile2: reading active writers
2024/11/09 04:42:09 DEBUG : virtualFile: reading active writers
2024/11/09 04:42:09 DEBUG : virtualDir: reading active writers
2024/11/09 04:42:09 DEBUG : : Looking for writers
2024/11/09 04:42:09 DEBUG : dir: reading active writers
2024/11/09 04:42:09 DEBUG : >WaitForWriters: 
--- PASS: TestDirReadDirAll (31.86s)
    --- PASS: TestDirReadDirAll/Virtual (12.75s)
=== RUN   TestDirOpen
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi", Local "Local file system at /tmp/rclone3837911696", Modify Window "1ms"
2024/11/09 04:42:15 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: poll-interval is not supported by this remote
2024/11/09 04:42:19 ERROR : dir/: Can only open directories read only
2024/11/09 04:42:19 DEBUG : WaitForWriters: timeout=30s
2024/11/09 04:42:19 DEBUG : dir: Looking for writers
2024/11/09 04:42:19 DEBUG : : Looking for writers
2024/11/09 04:42:19 DEBUG : dir: reading active writers
2024/11/09 04:42:19 DEBUG : >WaitForWriters: 
--- PASS: TestDirOpen (5.99s)
PASS
2024/11/09 04:42:22 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-demewir0qihi: Purge dir ""
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestKoofr: -verbose -test.run '^(TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMethods|TestDirOpen|TestDirReadDirAll|TestDirSetModTime|TestDirStat|TestDirWalk)$'" - Finished OK in 1m56.428995062s (try 2/5)