"./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 3/5)
2024/10/30 06:15:21 DEBUG : Creating backend with remote "TestKoofr:rclone-test-sevoyoz6xima"
2024/10/30 06:15:21 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/10/30 06:15:22 DEBUG : Creating backend with remote "/tmp/rclone3311590988"
=== RUN   TestDirHandleMethods
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:22 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:15:24 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:15:24 DEBUG : dir: Looking for writers
2024/10/30 06:15:24 DEBUG : : Looking for writers
2024/10/30 06:15:24 DEBUG : dir: reading active writers
2024/10/30 06:15:24 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleMethods (3.14s)
=== RUN   TestDirHandleReaddir
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:25 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:15:29 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:15:29 DEBUG : dir/subdir: Looking for writers
2024/10/30 06:15:29 DEBUG : dir: Looking for writers
2024/10/30 06:15:29 DEBUG : subdir: reading active writers
2024/10/30 06:15:29 DEBUG : file1: reading active writers
2024/10/30 06:15:29 DEBUG : file2: reading active writers
2024/10/30 06:15:29 DEBUG : : Looking for writers
2024/10/30 06:15:29 DEBUG : dir: reading active writers
2024/10/30 06:15:29 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleReaddir (9.17s)
=== RUN   TestDirHandleReaddirnames
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:34 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:15:39 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:15:39 DEBUG : dir: Looking for writers
2024/10/30 06:15:39 DEBUG : file1: reading active writers
2024/10/30 06:15:39 DEBUG : : Looking for writers
2024/10/30 06:15:39 DEBUG : dir: reading active writers
2024/10/30 06:15:39 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleReaddirnames (6.46s)
=== RUN   TestDirMethods
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:41 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:15:43 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:15:43 DEBUG : dir: Looking for writers
2024/10/30 06:15:43 DEBUG : : Looking for writers
2024/10/30 06:15:43 DEBUG : dir: reading active writers
2024/10/30 06:15:43 DEBUG : >WaitForWriters: 
--- PASS: TestDirMethods (4.48s)
=== RUN   TestDirForgetAll
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:45 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:15:48 DEBUG : dir: forgetting directory cache
2024/10/30 06:15:48 DEBUG : : forgetting directory cache
2024/10/30 06:15:48 DEBUG : dir: forgetting directory cache
2024/10/30 06:15:48 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:15:48 DEBUG : : Looking for writers
2024/10/30 06:15:48 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetAll (4.30s)
=== RUN   TestDirForgetPath
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:49 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:15:51 DEBUG : : ForgetPath: relativePath="dir/notfound", type=1
2024/10/30 06:15:51 DEBUG : dir: invalidating directory cache
2024/10/30 06:15:51 DEBUG : : >ForgetPath: 
2024/10/30 06:15:51 DEBUG : : ForgetPath: relativePath="dir", type=0
2024/10/30 06:15:51 DEBUG : : invalidating directory cache
2024/10/30 06:15:51 DEBUG : dir: forgetting directory cache
2024/10/30 06:15:51 DEBUG : : >ForgetPath: 
2024/10/30 06:15:51 DEBUG : : ForgetPath: relativePath="not/in/cache", type=0
2024/10/30 06:15:51 DEBUG : : >ForgetPath: 
2024/10/30 06:15:51 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:15:51 DEBUG : dir: Looking for writers
2024/10/30 06:15:51 DEBUG : : Looking for writers
2024/10/30 06:15:51 DEBUG : dir: reading active writers
2024/10/30 06:15:51 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetPath (2.82s)
=== RUN   TestDirWalk
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:52 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:15:55 DEBUG : : forgetting directory cache
2024/10/30 06:15:55 DEBUG : dir: forgetting directory cache
2024/10/30 06:15:55 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:15:55 DEBUG : dir: Looking for writers
2024/10/30 06:15:55 DEBUG : fil/a/b: Looking for writers
2024/10/30 06:15:55 DEBUG : fil/a: Looking for writers
2024/10/30 06:15:55 DEBUG : b: reading active writers
2024/10/30 06:15:55 DEBUG : fil: Looking for writers
2024/10/30 06:15:55 DEBUG : a: reading active writers
2024/10/30 06:15:55 DEBUG : : Looking for writers
2024/10/30 06:15:55 DEBUG : dir: reading active writers
2024/10/30 06:15:55 DEBUG : fil: reading active writers
2024/10/30 06:15:55 DEBUG : >WaitForWriters: 
--- PASS: TestDirWalk (4.91s)
=== RUN   TestDirSetModTime
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:57 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:15:58 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:15:58 DEBUG : dir: Looking for writers
2024/10/30 06:15:58 DEBUG : : Looking for writers
2024/10/30 06:15:58 DEBUG : dir: reading active writers
2024/10/30 06:15:58 DEBUG : >WaitForWriters: 
--- PASS: TestDirSetModTime (1.75s)
=== RUN   TestDirStat
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:15:59 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:16:01 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:16:01 DEBUG : dir: Looking for writers
2024/10/30 06:16:01 DEBUG : file1: reading active writers
2024/10/30 06:16:01 DEBUG : : Looking for writers
2024/10/30 06:16:01 DEBUG : dir: reading active writers
2024/10/30 06:16:01 DEBUG : >WaitForWriters: 
--- PASS: TestDirStat (2.52s)
=== RUN   TestDirReadDirAll
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:16:01 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
=== RUN   TestDirReadDirAll/Virtual
2024/10/30 06:16:05 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile"
2024/10/30 06:16:05 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir"
2024/10/30 06:16:05 DEBUG : dir: Added virtual directory entry vDel: "file2"
2024/10/30 06:16:05 DEBUG : dir: Added virtual directory entry vDel: "subdir"
2024/10/30 06:16:08 DEBUG : Waiting for deletions to finish
2024/10/30 06:16:09 INFO  : dir/subdir/file3: Deleted
2024/10/30 06:16:09 DEBUG : removing 1 level 2 directories
2024/10/30 06:16:09 INFO  : dir/subdir: Removing directory
2024/10/30 06:16:09 DEBUG : dir: invalidating directory cache
2024/10/30 06:16:10 DEBUG : dir: Removed virtual directory entry vDel: "subdir"
2024/10/30 06:16:10 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir"
2024/10/30 06:16:10 DEBUG : dir: Removed virtual directory entry vDel: "file2"
2024/10/30 06:16:10 DEBUG : dir/file1: Reset virtual modtime
2024/10/30 06:16:10 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile"
2024/10/30 06:16:10 DEBUG : dir/virtualFile: Reset virtual modtime
2024/10/30 06:16:10 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2"
2024/10/30 06:16:10 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2"
2024/10/30 06:16:10 DEBUG : dir: Added virtual directory entry vDel: "file1"
2024/10/30 06:16:10 DEBUG : dir: invalidating directory cache
2024/10/30 06:16:10 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2"
2024/10/30 06:16:10 DEBUG : dir: Removed virtual directory entry vDel: "file1"
2024/10/30 06:16:10 DEBUG : dir/virtualFile: Reset virtual modtime
2024/10/30 06:16:10 DEBUG : : forgetting directory cache
2024/10/30 06:16:10 DEBUG : dir: forgetting directory cache
2024/10/30 06:16:10 DEBUG : dir/virtualDir: forgetting directory cache
2024/10/30 06:16:10 DEBUG : dir/file1: Reset virtual modtime
2024/10/30 06:16:10 DEBUG : dir/virtualFile: Reset virtual modtime
2024/10/30 06:16:10 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:16:10 DEBUG : dir/virtualDir: Looking for writers
2024/10/30 06:16:10 DEBUG : dir: Looking for writers
2024/10/30 06:16:10 DEBUG : file1: reading active writers
2024/10/30 06:16:10 DEBUG : virtualFile2: reading active writers
2024/10/30 06:16:10 DEBUG : virtualFile: reading active writers
2024/10/30 06:16:10 DEBUG : virtualDir: reading active writers
2024/10/30 06:16:10 DEBUG : : Looking for writers
2024/10/30 06:16:10 DEBUG : dir: reading active writers
2024/10/30 06:16:10 DEBUG : >WaitForWriters: 
--- PASS: TestDirReadDirAll (10.99s)
    --- PASS: TestDirReadDirAll/Virtual (4.82s)
=== RUN   TestDirOpen
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima", Local "Local file system at /tmp/rclone3311590988", Modify Window "1ms"
2024/10/30 06:16:12 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: poll-interval is not supported by this remote
2024/10/30 06:16:14 ERROR : dir/: Can only open directories read only
2024/10/30 06:16:14 DEBUG : WaitForWriters: timeout=30s
2024/10/30 06:16:14 DEBUG : dir: Looking for writers
2024/10/30 06:16:14 DEBUG : : Looking for writers
2024/10/30 06:16:14 DEBUG : dir: reading active writers
2024/10/30 06:16:14 DEBUG : >WaitForWriters: 
--- PASS: TestDirOpen (3.59s)
PASS
2024/10/30 06:16:16 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-sevoyoz6xima: 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 55.581166677s (try 3/5)