"./vfs.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestDirWalk|TestReadFileHandleMethods)$|^TestFileSetModTime$/^cache=full,open=true,write=false$'" - Starting (try 2/5) 2025/04/01 06:19:07 DEBUG : Creating backend with remote "TestYandex:rclone-test-qonayok0cupe" 2025/04/01 06:19:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/01 06:19:07 DEBUG : Creating backend with remote "/tmp/rclone1461992076" === RUN TestDirWalk run.go:180: Remote "Yandex rclone-test-qonayok0cupe", Local "Local file system at /tmp/rclone1461992076", Modify Window "1ns" 2025/04/01 06:19:07 INFO : Yandex rclone-test-qonayok0cupe: poll-interval is not supported by this remote 2025/04/01 06:19:15 DEBUG : : forgetting directory cache 2025/04/01 06:19:15 DEBUG : dir: forgetting directory cache 2025/04/01 06:19:16 DEBUG : WaitForWriters: timeout=30s 2025/04/01 06:19:16 DEBUG : dir: Looking for writers 2025/04/01 06:19:16 DEBUG : fil/a/b: Looking for writers 2025/04/01 06:19:16 DEBUG : fil/a: Looking for writers 2025/04/01 06:19:16 DEBUG : b: reading active writers 2025/04/01 06:19:16 DEBUG : fil: Looking for writers 2025/04/01 06:19:16 DEBUG : a: reading active writers 2025/04/01 06:19:16 DEBUG : : Looking for writers 2025/04/01 06:19:16 DEBUG : dir: reading active writers 2025/04/01 06:19:16 DEBUG : fil: reading active writers 2025/04/01 06:19:16 DEBUG : >WaitForWriters: --- PASS: TestDirWalk (45.38s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:180: Remote "Yandex rclone-test-qonayok0cupe", Local "Local file system at /tmp/rclone1461992076", Modify Window "1ns" 2025/04/01 06:19:52 INFO : Yandex rclone-test-qonayok0cupe: poll-interval is not supported by this remote 2025/04/01 06:19:52 DEBUG : Yandex rclone-test-qonayok0cupe: vfs cache: root is "/home/rclone/.cache/rclone" 2025/04/01 06:19:52 DEBUG : Yandex rclone-test-qonayok0cupe: vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestYandex/rclone-test-qonayok0cupe" 2025/04/01 06:19:52 DEBUG : Yandex rclone-test-qonayok0cupe: vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestYandex/rclone-test-qonayok0cupe" 2025/04/01 06:19:52 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestYandex/rclone-test-qonayok0cupe" 2025/04/01 06:19:52 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/04/01 06:19:52 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestYandex/rclone-test-qonayok0cupe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestYandex/rclone-test-qonayok0cupe" 2025/04/01 06:19:52 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestYandex/rclone-test-qonayok0cupe" 2025/04/01 06:19:52 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/04/01 06:19:52 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestYandex/rclone-test-qonayok0cupe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestYandex/rclone-test-qonayok0cupe" 2025/04/01 06:19:52 INFO : Yandex rclone-test-qonayok0cupe: vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2025/04/01 06:20:03 DEBUG : Can set mod time: true 2025/04/01 06:20:03 DEBUG : dir/file1: Open: flags=O_WRONLY 2025/04/01 06:20:03 DEBUG : dir/file1: newRWFileHandle: 2025/04/01 06:20:03 DEBUG : dir/file1: >newRWFileHandle: err= 2025/04/01 06:20:03 DEBUG : dir/file1: >Open: fd=dir/file1 (rw), err= 2025/04/01 06:20:03 DEBUG : dir/file1(0xc0004179c0): close: 2025/04/01 06:20:03 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/04/01 06:20:03 DEBUG : dir/file1(0xc0004179c0): >close: err= 2025/04/01 06:20:03 DEBUG : WaitForWriters: timeout=30s 2025/04/01 06:20:03 DEBUG : dir: Looking for writers 2025/04/01 06:20:03 DEBUG : file1: reading active writers 2025/04/01 06:20:03 DEBUG : : Looking for writers 2025/04/01 06:20:03 DEBUG : dir: reading active writers 2025/04/01 06:20:03 DEBUG : >WaitForWriters: 2025/04/01 06:20:04 DEBUG : WaitForWriters: timeout=30s 2025/04/01 06:20:04 DEBUG : dir: Looking for writers 2025/04/01 06:20:04 DEBUG : file1: reading active writers 2025/04/01 06:20:04 DEBUG : : Looking for writers 2025/04/01 06:20:04 DEBUG : dir: reading active writers 2025/04/01 06:20:04 DEBUG : >WaitForWriters: 2025/04/01 06:20:04 DEBUG : Yandex rclone-test-qonayok0cupe: vfs cache: cleaner exiting --- PASS: TestFileSetModTime (22.79s) --- PASS: TestFileSetModTime/cache=full,open=true,write=false (22.79s) === RUN TestReadFileHandleMethods run.go:180: Remote "Yandex rclone-test-qonayok0cupe", Local "Local file system at /tmp/rclone1461992076", Modify Window "1ns" 2025/04/01 06:20:15 INFO : Yandex rclone-test-qonayok0cupe: poll-interval is not supported by this remote fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:19 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_test.go:40 Error: Should be true Test: TestReadFileHandleMethods Messages: dir/file1: Modification time difference too big |-211754h15m12.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-04-01 06:20:19 +0000 UTC) (precision 1ns) 2025/04/01 06:20:21 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/04/01 06:20:22 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/04/01 06:20:22 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/04/01 06:20:22 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2025/04/01 06:20:22 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/04/01 06:20:23 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728 2025/04/01 06:20:23 DEBUG : dir/file1: ChunkedReader.Read at 1 length 256 chunkOffset 0 chunkSize 134217728 2025/04/01 06:20:23 DEBUG : WaitForWriters: timeout=30s 2025/04/01 06:20:23 DEBUG : dir: Looking for writers 2025/04/01 06:20:23 DEBUG : file1: reading active writers 2025/04/01 06:20:23 DEBUG : : Looking for writers 2025/04/01 06:20:23 DEBUG : dir: reading active writers 2025/04/01 06:20:23 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (18.70s) FAIL 2025/04/01 06:20:34 DEBUG : Yandex rclone-test-qonayok0cupe: Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestDirWalk|TestReadFileHandleMethods)$|^TestFileSetModTime$/^cache=full,open=true,write=false$'" - Finished ERROR in 1m31.967928414s (try 2/5): exit status 1: Failed [TestReadFileHandleMethods]