"./vfs.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestDirWalk|TestWriteFileHandleFlush)$|^TestDirReadDirAll$/^Virtual$|^TestFileSetModTime$/^(cache=off,open=false,write=false|cache=off,open=true,write=false)$'" - Starting (try 2/5) 2026/02/22 05:31:32 DEBUG : Creating backend with remote "TestInternxt:rclone-test-nuliloz4somu" 2026/02/22 05:31:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 05:31:32 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/22 05:31:33 DEBUG : Creating backend with remote "/tmp/rclone1754401660" === RUN TestDirWalk run.go:185: Remote "Internxt root 'rclone-test-nuliloz4somu'", Local "Local file system at /tmp/rclone1754401660", Modify Window "876000h0m0s" 2026/02/22 05:31:33 INFO : Internxt root 'rclone-test-nuliloz4somu': poll-interval is not supported by this remote 2026/02/22 05:31:33 NOTICE: Internxt root 'rclone-test-nuliloz4somu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/22 05:31:37 DEBUG : forgetting directory cache 2026/02/22 05:31:37 DEBUG : dir: forgetting directory cache 2026/02/22 05:31:38 DEBUG : WaitForWriters: timeout=30s 2026/02/22 05:31:38 DEBUG : dir: Looking for writers 2026/02/22 05:31:38 DEBUG : fil/a/b: Looking for writers 2026/02/22 05:31:38 DEBUG : fil/a: Looking for writers 2026/02/22 05:31:38 DEBUG : b: reading active writers 2026/02/22 05:31:38 DEBUG : fil: Looking for writers 2026/02/22 05:31:38 DEBUG : a: reading active writers 2026/02/22 05:31:38 DEBUG : Looking for writers 2026/02/22 05:31:38 DEBUG : dir: reading active writers 2026/02/22 05:31:38 DEBUG : fil: reading active writers 2026/02/22 05:31:38 DEBUG : >WaitForWriters: run.go:130: removing dir "fil" failed - try 1/5: directory not empty 2026/02/22 05:31:42 ERROR : dir: error listing: directory not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestDirWalk (15.94s) === RUN TestDirReadDirAll run.go:185: Remote "Internxt root 'rclone-test-nuliloz4somu'", Local "Local file system at /tmp/rclone1754401660", Modify Window "876000h0m0s" 2026/02/22 05:31:49 INFO : Internxt root 'rclone-test-nuliloz4somu': poll-interval is not supported by this remote 2026/02/22 05:31:49 NOTICE: Internxt root 'rclone-test-nuliloz4somu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream === RUN TestDirReadDirAll/Virtual 2026/02/22 05:31:53 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2026/02/22 05:31:53 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2026/02/22 05:31:53 DEBUG : dir: Added virtual directory entry vDel: "file2" 2026/02/22 05:31:53 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2026/02/22 05:31:55 DEBUG : Waiting for deletions to finish 2026/02/22 05:31:55 INFO : dir/subdir/file3: Deleted 2026/02/22 05:31:55 DEBUG : removing 1 level 2 directories 2026/02/22 05:31:55 INFO : dir/subdir: Removing directory 2026/02/22 05:31:56 DEBUG : dir: invalidating directory cache 2026/02/22 05:31:56 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2026/02/22 05:31:56 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2026/02/22 05:31:56 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2026/02/22 05:31:56 DEBUG : dir/file1: Reset virtual modtime 2026/02/22 05:31:56 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2026/02/22 05:31:56 DEBUG : dir/virtualFile: Reset virtual modtime dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:300 Error: Not equal: expected: []string{"file1,14,false", "virtualDir,0,true", "virtualFile,20,false"} actual : []string{"file1,14,false", "subdir,0,true", "virtualDir,0,true", "virtualFile,20,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=3) { +([]string) (len=4) { (string) (len=14) "file1,14,false", + (string) (len=13) "subdir,0,true", (string) (len=17) "virtualDir,0,true", Test: TestDirReadDirAll/Virtual 2026/02/22 05:31:56 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2026/02/22 05:31:56 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2026/02/22 05:31:56 DEBUG : dir: Added virtual directory entry vDel: "file1" dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:313 Error: Not equal: expected: []string{"virtualDir,0,true", "virtualDir2,0,true", "virtualFile,20,false", "virtualFile2,100,false"} actual : []string{"subdir,0,true", "virtualDir,0,true", "virtualDir2,0,true", "virtualFile,20,false", "virtualFile2,100,false"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=4) { +([]string) (len=5) { + (string) (len=13) "subdir,0,true", (string) (len=17) "virtualDir,0,true", Test: TestDirReadDirAll/Virtual 2026/02/22 05:31:56 DEBUG : dir: invalidating directory cache 2026/02/22 05:31:56 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2" 2026/02/22 05:31:56 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2026/02/22 05:31:56 DEBUG : dir/virtualFile: Reset virtual modtime 2026/02/22 05:31:56 DEBUG : forgetting directory cache 2026/02/22 05:31:56 DEBUG : dir: forgetting directory cache 2026/02/22 05:31:56 DEBUG : dir/virtualDir: forgetting directory cache 2026/02/22 05:31:56 DEBUG : WaitForWriters: timeout=30s 2026/02/22 05:31:56 DEBUG : dir/virtualDir: Looking for writers 2026/02/22 05:31:56 DEBUG : dir: Looking for writers 2026/02/22 05:31:56 DEBUG : file1: reading active writers 2026/02/22 05:31:56 DEBUG : virtualFile2: reading active writers 2026/02/22 05:31:56 DEBUG : virtualFile: reading active writers 2026/02/22 05:31:56 DEBUG : virtualDir: reading active writers 2026/02/22 05:31:56 DEBUG : Looking for writers 2026/02/22 05:31:56 DEBUG : dir: reading active writers 2026/02/22 05:31:56 DEBUG : >WaitForWriters: === NAME TestDirReadDirAll run.go:130: removing dir "dir" failed - try 1/5: directory not empty 2026/02/22 05:32:00 ERROR : dir: error listing: directory not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestDirReadDirAll (18.31s) --- FAIL: TestDirReadDirAll/Virtual (3.16s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:185: Remote "Internxt root 'rclone-test-nuliloz4somu'", Local "Local file system at /tmp/rclone1754401660", Modify Window "876000h0m0s" 2026/02/22 05:32:07 INFO : Internxt root 'rclone-test-nuliloz4somu': poll-interval is not supported by this remote 2026/02/22 05:32:07 NOTICE: Internxt root 'rclone-test-nuliloz4somu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream write_test.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:299 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/sync/once.go:78 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/sync/once.go:69 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:295 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:96 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159 Error: Received unexpected error: object not found Test: TestFileSetModTime/cache=off,open=false,write=false 2026/02/22 05:32:10 DEBUG : WaitForWriters: timeout=30s 2026/02/22 05:32:10 DEBUG : dir: Looking for writers 2026/02/22 05:32:10 DEBUG : file1: reading active writers 2026/02/22 05:32:10 DEBUG : Looking for writers 2026/02/22 05:32:10 DEBUG : dir: reading active writers 2026/02/22 05:32:10 DEBUG : >WaitForWriters: 2026/02/22 05:32:11 ERROR : dir: error listing: directory not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:292: Sleeping for 6s just to make sure === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:185: Remote "Internxt root 'rclone-test-nuliloz4somu'", Local "Local file system at /tmp/rclone1754401660", Modify Window "876000h0m0s" 2026/02/22 05:32:19 INFO : Internxt root 'rclone-test-nuliloz4somu': poll-interval is not supported by this remote 2026/02/22 05:32:19 NOTICE: Internxt root 'rclone-test-nuliloz4somu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/22 05:32:20 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2026/02/22 05:32:20 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2026/02/22 05:32:20 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/22 05:32:20 DEBUG : Internxt root 'rclone-test-nuliloz4somu': File to upload is small (0 bytes), uploading instead of streaming 2026/02/22 05:32:21 DEBUG : Internxt root 'rclone-test-nuliloz4somu': Renamed existing file dir/file1 to backup file1.rclone-backup-helucin3. (UUID: c4ed0e01-2e69-4413-8bd8-0c7b396d5faf) 2026/02/22 05:32:22 DEBUG : Internxt root 'rclone-test-nuliloz4somu': Upload succeeded, deleting backup file file1.rclone-backup-helucin3. (UUID: c4ed0e01-2e69-4413-8bd8-0c7b396d5faf) 2026/02/22 05:32:22 DEBUG : Internxt root 'rclone-test-nuliloz4somu': Successfully deleted backup file 2026/02/22 05:32:22 DEBUG : dir/file1: size = 0 OK 2026/02/22 05:32:22 NOTICE: Internxt root 'rclone-test-nuliloz4somu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/02/22 05:32:22 DEBUG : dir/file1: Size of src and dst objects identical 2026/02/22 05:32:22 DEBUG : dir/file1: Not setting pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC as it is already set 2026/02/22 05:32:22 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/22 05:32:22 DEBUG : WaitForWriters: timeout=30s 2026/02/22 05:32:22 DEBUG : dir: Looking for writers 2026/02/22 05:32:22 DEBUG : file1: reading active writers 2026/02/22 05:32:22 DEBUG : Looking for writers 2026/02/22 05:32:22 DEBUG : dir: reading active writers 2026/02/22 05:32:22 DEBUG : >WaitForWriters: 2026/02/22 05:32:23 DEBUG : WaitForWriters: timeout=30s 2026/02/22 05:32:23 DEBUG : dir: Looking for writers 2026/02/22 05:32:23 DEBUG : file1: reading active writers 2026/02/22 05:32:23 DEBUG : Looking for writers 2026/02/22 05:32:23 DEBUG : dir: reading active writers 2026/02/22 05:32:23 DEBUG : >WaitForWriters: run.go:130: removing dir "dir" failed - try 1/5: directory not empty 2026/02/22 05:32:27 ERROR : dir: error listing: directory not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestFileSetModTime (26.51s) --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (11.30s) --- PASS: TestFileSetModTime/cache=off,open=true,write=false (15.21s) === RUN TestWriteFileHandleFlush run.go:185: Remote "Internxt root 'rclone-test-nuliloz4somu'", Local "Local file system at /tmp/rclone1754401660", Modify Window "876000h0m0s" 2026/02/22 05:32:34 INFO : Internxt root 'rclone-test-nuliloz4somu': poll-interval is not supported by this remote 2026/02/22 05:32:34 NOTICE: Internxt root 'rclone-test-nuliloz4somu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/22 05:32:34 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/02/22 05:32:34 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/02/22 05:32:34 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/22 05:32:34 DEBUG : file1: >Open: fd=file1 (w), err= 2026/02/22 05:32:34 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/02/22 05:32:34 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2026/02/22 05:32:34 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/22 05:32:34 DEBUG : Internxt root 'rclone-test-nuliloz4somu': File to upload is small (5 bytes), uploading instead of streaming 2026/02/22 05:32:35 DEBUG : file1: size = 5 OK 2026/02/22 05:32:35 DEBUG : file1: Size of src and dst objects identical 2026/02/22 05:32:35 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/22 05:32:35 DEBUG : file1: WriteFileHandle.Flush nothing to do 2026/02/22 05:32:35 DEBUG : WaitForWriters: timeout=30s 2026/02/22 05:32:35 DEBUG : Looking for writers 2026/02/22 05:32:35 DEBUG : file1: reading active writers 2026/02/22 05:32:35 DEBUG : >WaitForWriters: --- PASS: TestWriteFileHandleFlush (1.42s) FAIL 2026/02/22 05:32:36 DEBUG : Internxt root 'rclone-test-nuliloz4somu': Purge dir "" "./vfs.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestDirWalk|TestWriteFileHandleFlush)$|^TestDirReadDirAll$/^Virtual$|^TestFileSetModTime$/^(cache=off,open=false,write=false|cache=off,open=true,write=false)$'" - Finished ERROR in 1m3.993490843s (try 2/5): exit status 1: Failed [TestDirReadDirAll/Virtual TestFileSetModTime/cache=off,open=false,write=false]