"./vfs.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestDirMetadataExtension|TestZipManyFiles)$|^TestFileRename$/^off,forceCache=false$|^TestFileSetModTime$/^cache=off,open=true,write=false$'" - Starting (try 2/5)
2025/12/16 05:36:14 DEBUG : Creating backend with remote "TestYandex:rclone-test-hizolab5coku"
2025/12/16 05:36:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/12/16 05:36:15 DEBUG : Creating backend with remote "/tmp/rclone864409643"
=== RUN   TestDirMetadataExtension
    run.go:185: Remote "Yandex rclone-test-hizolab5coku", Local "Local file system at /tmp/rclone864409643", Modify Window "1ns"
2025/12/16 05:36:15 INFO  : Yandex rclone-test-hizolab5coku: poll-interval is not supported by this remote
2025/12/16 05:36:18 DEBUG : dir: Added virtual directory entry vAddFile: "file1.metadata"
2025/12/16 05:36:18 DEBUG : dir/file1.metadata: OpenFile: flags=O_RDONLY, perm=----------
2025/12/16 05:36:18 DEBUG : dir/file1.metadata: Open: flags=O_RDONLY
2025/12/16 05:36:18 DEBUG : dir/file1.metadata: >Open: fd=dir/file1.metadata (r), err=<nil>
2025/12/16 05:36:18 DEBUG : dir/file1.metadata: >OpenFile: fd=dir/file1.metadata (r), err=<nil>
2025/12/16 05:36:18 DEBUG : dir/file1.metadata: ChunkedReader.openRange at 0 length 134217728
2025/12/16 05:36:18 DEBUG : dir/file1.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728
2025/12/16 05:36:18 DEBUG : Added virtual directory entry vAddFile: "dir.metadata"
2025/12/16 05:36:18 DEBUG : dir.metadata: OpenFile: flags=O_RDONLY, perm=----------
2025/12/16 05:36:18 DEBUG : dir.metadata: Open: flags=O_RDONLY
2025/12/16 05:36:18 DEBUG : dir.metadata: >Open: fd=dir.metadata (r), err=<nil>
2025/12/16 05:36:18 DEBUG : dir.metadata: >OpenFile: fd=dir.metadata (r), err=<nil>
2025/12/16 05:36:18 DEBUG : dir.metadata: ChunkedReader.openRange at 0 length 134217728
2025/12/16 05:36:18 DEBUG : dir.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728
2025/12/16 05:36:18 DEBUG : WaitForWriters: timeout=30s
2025/12/16 05:36:18 DEBUG : dir: Looking for writers
2025/12/16 05:36:18 DEBUG : file1: reading active writers
2025/12/16 05:36:18 DEBUG : file1.metadata: reading active writers
2025/12/16 05:36:18 DEBUG : Looking for writers
2025/12/16 05:36:18 DEBUG : dir: reading active writers
2025/12/16 05:36:18 DEBUG : dir.metadata: reading active writers
2025/12/16 05:36:18 DEBUG : >WaitForWriters: 
--- PASS: TestDirMetadataExtension (17.35s)
=== RUN   TestFileSetModTime
=== RUN   TestFileSetModTime/cache=off,open=true,write=false
    run.go:185: Remote "Yandex rclone-test-hizolab5coku", Local "Local file system at /tmp/rclone864409643", Modify Window "1ns"
2025/12/16 05:36:32 INFO  : Yandex rclone-test-hizolab5coku: poll-interval is not supported by this remote
2025/12/16 05:36:43 DEBUG : Can set mod time: true
2025/12/16 05:36:43 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2025/12/16 05:36:43 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err=<nil>
2025/12/16 05:36:43 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2025/12/16 05:36:43 DEBUG : Yandex rclone-test-hizolab5coku: File to upload is small (0 bytes), uploading instead of streaming
2025/12/16 05:36:46 DEBUG : dir/file1: size = 0 OK
2025/12/16 05:36:46 DEBUG : dir/file1: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/16 05:36:46 DEBUG : dir/file1: Size and md5 of src and dst objects identical
2025/12/16 05:36:46 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK
2025/12/16 05:36:46 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2025/12/16 05:36:46 DEBUG : WaitForWriters: timeout=30s
2025/12/16 05:36:46 DEBUG : dir: Looking for writers
2025/12/16 05:36:46 DEBUG : file1: reading active writers
2025/12/16 05:36:46 DEBUG : Looking for writers
2025/12/16 05:36:46 DEBUG : dir: reading active writers
2025/12/16 05:36:46 DEBUG : >WaitForWriters: 
2025/12/16 05:36:47 DEBUG : WaitForWriters: timeout=30s
2025/12/16 05:36:47 DEBUG : dir: Looking for writers
2025/12/16 05:36:47 DEBUG : file1: reading active writers
2025/12/16 05:36:47 DEBUG : Looking for writers
2025/12/16 05:36:47 DEBUG : dir: reading active writers
2025/12/16 05:36:47 DEBUG : >WaitForWriters: 
--- PASS: TestFileSetModTime (29.45s)
    --- PASS: TestFileSetModTime/cache=off,open=true,write=false (29.45s)
=== RUN   TestFileRename
=== RUN   TestFileRename/off,forceCache=false
    run.go:185: Remote "Yandex rclone-test-hizolab5coku", Local "Local file system at /tmp/rclone864409643", Modify Window "1ns"
2025/12/16 05:37:01 INFO  : Yandex rclone-test-hizolab5coku: poll-interval is not supported by this remote
2025/12/16 05:37:05 DEBUG : WaitForWriters: timeout=30s
2025/12/16 05:37:05 DEBUG : dir: Looking for writers
2025/12/16 05:37:05 DEBUG : file1: reading active writers
2025/12/16 05:37:05 DEBUG : Looking for writers
2025/12/16 05:37:05 DEBUG : dir: reading active writers
2025/12/16 05:37:05 DEBUG : >WaitForWriters: 
2025/12/16 05:37:08 INFO  : dir/file1: Moved (server-side) to: newLeaf
2025/12/16 05:37:08 DEBUG : newLeaf: Updating file with newLeaf 0xc000a34240
2025/12/16 05:37:08 DEBUG : dir: Added virtual directory entry vDel: "file1"
2025/12/16 05:37:08 DEBUG : Added virtual directory entry vAddFile: "newLeaf"
2025/12/16 05:37:10 INFO  : newLeaf: Moved (server-side) to: dir/file1
2025/12/16 05:37:10 DEBUG : dir/file1: Updating file with dir/file1 0xc000a34240
2025/12/16 05:37:10 DEBUG : Added virtual directory entry vDel: "newLeaf"
2025/12/16 05:37:10 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2025/12/16 05:37:11 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2025/12/16 05:37:11 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err=<nil>
2025/12/16 05:37:11 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2025/12/16 05:37:11 DEBUG : dir/file1: File is currently open, delaying rename 0xc000a34240
2025/12/16 05:37:11 DEBUG : dir: Added virtual directory entry vDel: "file1"
2025/12/16 05:37:11 DEBUG : Added virtual directory entry vAddFile: "newLeaf"
2025/12/16 05:37:11 DEBUG : Yandex rclone-test-hizolab5coku: File to upload is small (25 bytes), uploading instead of streaming
2025/12/16 05:37:13 DEBUG : dir/file1: size = 25 OK
2025/12/16 05:37:13 DEBUG : dir/file1: md5 = c7247bead226dfd7bd970c17229f4044 OK
2025/12/16 05:37:13 DEBUG : dir/file1: Size and md5 of src and dst objects identical
2025/12/16 05:37:13 DEBUG : Added virtual directory entry vAddFile: "newLeaf"
2025/12/16 05:37:13 DEBUG : newLeaf: Running delayed rename now
2025/12/16 05:37:15 INFO  : dir/file1: Moved (server-side) to: newLeaf
2025/12/16 05:37:15 DEBUG : newLeaf: Updating file with newLeaf 0xc000a34240
2025/12/16 05:37:15 DEBUG : WaitForWriters: timeout=30s
2025/12/16 05:37:15 DEBUG : dir: Looking for writers
2025/12/16 05:37:15 DEBUG : Looking for writers
2025/12/16 05:37:15 DEBUG : dir: reading active writers
2025/12/16 05:37:15 DEBUG : newLeaf: reading active writers
2025/12/16 05:37:15 DEBUG : >WaitForWriters: 
2025/12/16 05:37:16 DEBUG : WaitForWriters: timeout=30s
2025/12/16 05:37:16 DEBUG : dir: Looking for writers
2025/12/16 05:37:16 DEBUG : Looking for writers
2025/12/16 05:37:16 DEBUG : dir: reading active writers
2025/12/16 05:37:16 DEBUG : newLeaf: reading active writers
2025/12/16 05:37:16 DEBUG : >WaitForWriters: 
--- PASS: TestFileRename (27.65s)
    --- PASS: TestFileRename/off,forceCache=false (27.65s)
=== RUN   TestZipManyFiles
    run.go:185: Remote "Yandex rclone-test-hizolab5coku", Local "Local file system at /tmp/rclone864409643", Modify Window "1ns"
2025/12/16 05:37:29 INFO  : Yandex rclone-test-hizolab5coku: poll-interval is not supported by this remote
2025/12/16 05:37:40 DEBUG : flat/f000.txt: Open: flags=O_RDONLY
2025/12/16 05:37:40 DEBUG : flat/f000.txt: >Open: fd=flat/f000.txt (r), err=<nil>
2025/12/16 05:37:40 DEBUG : flat/f000.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/16 05:37:41 DEBUG : flat/f000.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/16 05:37:41 DEBUG : flat/f001.txt: Open: flags=O_RDONLY
2025/12/16 05:37:41 DEBUG : flat/f001.txt: >Open: fd=flat/f001.txt (r), err=<nil>
2025/12/16 05:37:41 DEBUG : flat/f001.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/16 05:37:41 DEBUG : flat/f001.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/16 05:37:41 DEBUG : flat/f002.txt: Open: flags=O_RDONLY
2025/12/16 05:37:41 DEBUG : flat/f002.txt: >Open: fd=flat/f002.txt (r), err=<nil>
2025/12/16 05:37:41 DEBUG : flat/f002.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/16 05:37:42 DEBUG : flat/f002.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/16 05:37:42 DEBUG : flat/f003.txt: Open: flags=O_RDONLY
2025/12/16 05:37:42 DEBUG : flat/f003.txt: >Open: fd=flat/f003.txt (r), err=<nil>
2025/12/16 05:37:42 DEBUG : flat/f003.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/16 05:37:42 DEBUG : flat/f003.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/16 05:37:42 DEBUG : flat/f004.txt: Open: flags=O_RDONLY
2025/12/16 05:37:42 DEBUG : flat/f004.txt: >Open: fd=flat/f004.txt (r), err=<nil>
2025/12/16 05:37:42 DEBUG : flat/f004.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/16 05:37:42 DEBUG : flat/f004.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/16 05:37:42 DEBUG : WaitForWriters: timeout=30s
2025/12/16 05:37:42 DEBUG : flat: Looking for writers
2025/12/16 05:37:42 DEBUG : f000.txt: reading active writers
2025/12/16 05:37:42 DEBUG : f001.txt: reading active writers
2025/12/16 05:37:42 DEBUG : f002.txt: reading active writers
2025/12/16 05:37:42 DEBUG : f003.txt: reading active writers
2025/12/16 05:37:42 DEBUG : f004.txt: reading active writers
2025/12/16 05:37:42 DEBUG : Looking for writers
2025/12/16 05:37:42 DEBUG : flat: reading active writers
2025/12/16 05:37:42 DEBUG : >WaitForWriters: 
--- PASS: TestZipManyFiles (49.90s)
PASS
2025/12/16 05:38:19 DEBUG : Yandex rclone-test-hizolab5coku: Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestDirMetadataExtension|TestZipManyFiles)$|^TestFileRename$/^off,forceCache=false$|^TestFileSetModTime$/^cache=off,open=true,write=false$'" - Finished OK in 2m10.995759698s (try 2/5)