"./vfs.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestDirFileOpen|TestVFSOpenFile)$'" - Starting (try 2/5) 2025/05/17 02:41:20 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-galohoq1muho" 2025/05/17 02:41:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/17 02:41:20 DEBUG : Creating backend with remote "TestYandex:rclone-test-galohoq1muho" 2025/05/17 02:41:21 DEBUG : Creating backend with remote "/tmp/rclone774831870" === RUN TestDirFileOpen run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-galohoq1muho'", Local "Local file system at /tmp/rclone774831870", Modify Window "1ns" 2025/05/17 02:41:21 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-galohoq1muho': poll-interval is not supported by this remote 2025/05/17 02:41:27 INFO : dir/file1.rclone_chunk.001_e2f5ky: Moved (server-side) to: dir/file1 2025/05/17 02:41:28 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/05/17 02:41:28 DEBUG : dir/sub/file0: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2025/05/17 02:41:29 DEBUG : dir/sub/file0: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2025/05/17 02:41:29 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/05/17 02:41:29 DEBUG : dir/sub/file0: >Open: fd=dir/sub/file0 (w), err= 2025/05/17 02:41:29 DEBUG : dir/sub/file0: >OpenFile: fd=dir/sub/file0 (w), err= 2025/05/17 02:41:29 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/05/17 02:41:29 DEBUG : dir/sub/file2: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2025/05/17 02:41:29 DEBUG : dir/sub/file2: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2025/05/17 02:41:29 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/05/17 02:41:29 DEBUG : dir/sub/file2: >Open: fd=dir/sub/file2 (w), err= 2025/05/17 02:41:29 DEBUG : dir/sub/file2: >OpenFile: fd=dir/sub/file2 (w), err= 2025/05/17 02:41:29 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/05/17 02:41:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-galohoq1muho': File to upload is small (12 bytes), uploading instead of streaming 2025/05/17 02:41:34 INFO : dir/sub/file2.rclone_chunk.001_e2fdju: Moved (server-side) to: dir/sub/file2 2025/05/17 02:41:34 DEBUG : dir/sub/file2: md5 = fc3ff98e8c6a0d3087d515c0473f8677 OK 2025/05/17 02:41:34 DEBUG : dir/sub/file2: Size and md5 of src and dst objects identical 2025/05/17 02:41:34 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/05/17 02:41:34 DEBUG : : forgetting directory cache 2025/05/17 02:41:34 DEBUG : dir: forgetting directory cache 2025/05/17 02:41:34 DEBUG : dir/sub: forgetting directory cache 2025/05/17 02:41:34 DEBUG : dir/sub: Removed virtual directory entry vAddFile: "file2" 2025/05/17 02:41:34 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2025/05/17 02:41:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-galohoq1muho': File to upload is small (5 bytes), uploading instead of streaming 2025/05/17 02:41:39 INFO : dir/sub/file0.rclone_chunk.001_e2fiy8: Moved (server-side) to: dir/sub/file0 2025/05/17 02:41:39 DEBUG : dir/sub/file0: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/05/17 02:41:39 DEBUG : dir/sub/file0: Size and md5 of src and dst objects identical 2025/05/17 02:41:39 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/05/17 02:41:39 DEBUG : WaitForWriters: timeout=30s 2025/05/17 02:41:39 DEBUG : dir/sub: Looking for writers 2025/05/17 02:41:39 DEBUG : file0: reading active writers 2025/05/17 02:41:39 DEBUG : file2: reading active writers 2025/05/17 02:41:39 DEBUG : dir: Looking for writers 2025/05/17 02:41:39 DEBUG : file1: reading active writers 2025/05/17 02:41:39 DEBUG : sub: reading active writers 2025/05/17 02:41:39 DEBUG : : Looking for writers 2025/05/17 02:41:39 DEBUG : dir: reading active writers 2025/05/17 02:41:39 DEBUG : >WaitForWriters: --- PASS: TestDirFileOpen (43.66s) === RUN TestVFSOpenFile run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-galohoq1muho'", Local "Local file system at /tmp/rclone774831870", Modify Window "1ns" 2025/05/17 02:42:04 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-galohoq1muho': poll-interval is not supported by this remote 2025/05/17 02:42:09 INFO : file1.rclone_chunk.001_e2gd5u: Moved (server-side) to: file1 2025/05/17 02:42:14 INFO : dir/file2.rclone_chunk.001_e2gizl: Moved (server-side) to: dir/file2 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/vfs_test.go:258 Error: Should be true Test: TestVFSOpenFile Messages: file1: Modification time difference too big |-212854h37m2.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-17 02:42:09 +0000 UTC) (precision 1ns) 2025/05/17 02:42:15 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/05/17 02:42:15 DEBUG : file1: Open: flags=O_RDONLY 2025/05/17 02:42:15 DEBUG : file1: >Open: fd=file1 (r), err= 2025/05/17 02:42:15 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2025/05/17 02:42:15 DEBUG : dir: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/05/17 02:42:15 DEBUG : dir: >OpenFile: fd=dir/ (r), err= 2025/05/17 02:42:15 DEBUG : dir/new_file.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/05/17 02:42:16 DEBUG : dir/new_file.txt: >OpenFile: fd=, err=file does not exist 2025/05/17 02:42:16 DEBUG : dir/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/05/17 02:42:16 DEBUG : dir/new_file.txt: Open: flags=O_WRONLY|O_CREATE 2025/05/17 02:42:16 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2025/05/17 02:42:16 DEBUG : dir/new_file.txt: >Open: fd=dir/new_file.txt (w), err= 2025/05/17 02:42:16 DEBUG : dir/new_file.txt: >OpenFile: fd=dir/new_file.txt (w), err= 2025/05/17 02:42:16 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2025/05/17 02:42:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-galohoq1muho': File to upload is small (0 bytes), uploading instead of streaming 2025/05/17 02:42:20 INFO : dir/new_file.txt.rclone_chunk.001_e2gote: Moved (server-side) to: dir/new_file.txt 2025/05/17 02:42:20 DEBUG : dir/new_file.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/17 02:42:20 DEBUG : dir/new_file.txt: Size and md5 of src and dst objects identical 2025/05/17 02:42:20 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2025/05/17 02:42:20 DEBUG : not found/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/05/17 02:42:20 DEBUG : not found/new_file.txt: >OpenFile: fd=, err=file does not exist 2025/05/17 02:42:20 DEBUG : WaitForWriters: timeout=30s 2025/05/17 02:42:20 DEBUG : dir: Looking for writers 2025/05/17 02:42:20 DEBUG : file2: reading active writers 2025/05/17 02:42:20 DEBUG : new_file.txt: reading active writers 2025/05/17 02:42:20 DEBUG : : Looking for writers 2025/05/17 02:42:20 DEBUG : dir: reading active writers 2025/05/17 02:42:20 DEBUG : file1: reading active writers 2025/05/17 02:42:20 DEBUG : >WaitForWriters: --- FAIL: TestVFSOpenFile (34.80s) FAIL 2025/05/17 02:42:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-galohoq1muho': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestDirFileOpen|TestVFSOpenFile)$'" - Finished ERROR in 1m23.824910831s (try 2/5): exit status 1: Failed [TestVFSOpenFile]