"./vfs.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestFileReadAtNonZeroLength|TestFileReadAtZeroLength)$|^TestDirReadDirAll$/^Virtual$'" - Starting (try 2/5) 2026/02/18 04:50:47 DEBUG : Creating backend with remote "TestInternxt:rclone-test-conusap3cife" 2026/02/18 04:50:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/18 04:50:47 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/18 04:50:48 DEBUG : Creating backend with remote "/tmp/rclone1638980676" === RUN TestDirReadDirAll run.go:185: Remote "Internxt root 'rclone-test-conusap3cife'", Local "Local file system at /tmp/rclone1638980676", Modify Window "876000h0m0s" 2026/02/18 04:50:48 INFO : Internxt root 'rclone-test-conusap3cife': poll-interval is not supported by this remote 2026/02/18 04:50:48 NOTICE: Internxt root 'rclone-test-conusap3cife': --vfs-cache-mode writes or full is recommended for this remote as it can't stream === RUN TestDirReadDirAll/Virtual 2026/02/18 04:50:52 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2026/02/18 04:50:52 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2026/02/18 04:50:52 DEBUG : dir: Added virtual directory entry vDel: "file2" 2026/02/18 04:50:52 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2026/02/18 04:50:54 DEBUG : Waiting for deletions to finish 2026/02/18 04:50:55 INFO : dir/subdir/file3: Deleted 2026/02/18 04:50:55 DEBUG : removing 1 level 2 directories 2026/02/18 04:50:55 INFO : dir/subdir: Removing directory 2026/02/18 04:50:55 DEBUG : dir: invalidating directory cache 2026/02/18 04:50:55 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2026/02/18 04:50:55 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2026/02/18 04:50:55 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2026/02/18 04:50:55 DEBUG : dir/file1: Reset virtual modtime 2026/02/18 04:50:55 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2026/02/18 04:50:55 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/18 04:50:55 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2026/02/18 04:50:55 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2026/02/18 04:50:55 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/18 04:50:55 DEBUG : dir: invalidating directory cache 2026/02/18 04:50:56 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2" 2026/02/18 04:50:56 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2026/02/18 04:50:56 DEBUG : dir/virtualFile: Reset virtual modtime 2026/02/18 04:50:56 DEBUG : forgetting directory cache 2026/02/18 04:50:56 DEBUG : dir: forgetting directory cache 2026/02/18 04:50:56 DEBUG : dir/virtualDir: forgetting directory cache 2026/02/18 04:50:56 DEBUG : WaitForWriters: timeout=30s 2026/02/18 04:50:56 DEBUG : dir/virtualDir: Looking for writers 2026/02/18 04:50:56 DEBUG : dir: Looking for writers 2026/02/18 04:50:56 DEBUG : file1: reading active writers 2026/02/18 04:50:56 DEBUG : virtualFile2: reading active writers 2026/02/18 04:50:56 DEBUG : virtualFile: reading active writers 2026/02/18 04:50:56 DEBUG : virtualDir: reading active writers 2026/02/18 04:50:56 DEBUG : Looking for writers 2026/02/18 04:50:56 DEBUG : dir: reading active writers 2026/02/18 04:50:56 DEBUG : >WaitForWriters: === NAME TestDirReadDirAll run.go:130: removing dir "dir" failed - try 1/5: directory not empty 2026/02/18 04:50:59 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.60s) --- FAIL: TestDirReadDirAll/Virtual (3.44s) === RUN TestFileReadAtZeroLength run.go:185: Remote "Internxt root 'rclone-test-conusap3cife'", Local "Local file system at /tmp/rclone1638980676", Modify Window "876000h0m0s" 2026/02/18 04:51:06 INFO : Internxt root 'rclone-test-conusap3cife': poll-interval is not supported by this remote 2026/02/18 04:51:06 NOTICE: Internxt root 'rclone-test-conusap3cife': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/18 04:51:06 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/02/18 04:51:06 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/02/18 04:51:06 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/18 04:51:06 DEBUG : file1: >Open: fd=file1 (w), err= 2026/02/18 04:51:06 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/02/18 04:51:06 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/18 04:51:06 DEBUG : Internxt root 'rclone-test-conusap3cife': File to upload is small (0 bytes), uploading instead of streaming 2026/02/18 04:51:07 DEBUG : file1: size = 0 OK 2026/02/18 04:51:07 NOTICE: Internxt root 'rclone-test-conusap3cife': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/02/18 04:51:07 DEBUG : file1: Size of src and dst objects identical 2026/02/18 04:51:07 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/18 04:51:07 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/02/18 04:51:07 DEBUG : file1: Open: flags=O_RDONLY 2026/02/18 04:51:07 DEBUG : file1: >Open: fd=file1 (r), err= 2026/02/18 04:51:07 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2026/02/18 04:51:07 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2026/02/18 04:51:07 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2026/02/18 04:51:07 DEBUG : WaitForWriters: timeout=30s 2026/02/18 04:51:07 DEBUG : Looking for writers 2026/02/18 04:51:07 DEBUG : file1: reading active writers 2026/02/18 04:51:07 DEBUG : >WaitForWriters: --- PASS: TestFileReadAtZeroLength (1.20s) === RUN TestFileReadAtNonZeroLength run.go:185: Remote "Internxt root 'rclone-test-conusap3cife'", Local "Local file system at /tmp/rclone1638980676", Modify Window "876000h0m0s" 2026/02/18 04:51:07 INFO : Internxt root 'rclone-test-conusap3cife': poll-interval is not supported by this remote 2026/02/18 04:51:07 NOTICE: Internxt root 'rclone-test-conusap3cife': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/18 04:51:07 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/02/18 04:51:08 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/02/18 04:51:08 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/18 04:51:08 DEBUG : file1: >Open: fd=file1 (w), err= 2026/02/18 04:51:08 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/02/18 04:51:08 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/18 04:51:08 DEBUG : Internxt root 'rclone-test-conusap3cife': File to upload is small (100 bytes), uploading instead of streaming 2026/02/18 04:51:08 DEBUG : file1: size = 100 OK 2026/02/18 04:51:08 DEBUG : file1: Size of src and dst objects identical 2026/02/18 04:51:08 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/02/18 04:51:08 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2026/02/18 04:51:08 DEBUG : file1: Open: flags=O_RDONLY 2026/02/18 04:51:08 DEBUG : file1: >Open: fd=file1 (r), err= 2026/02/18 04:51:08 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2026/02/18 04:51:08 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2026/02/18 04:51:09 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2026/02/18 04:51:09 DEBUG : WaitForWriters: timeout=30s 2026/02/18 04:51:09 DEBUG : Looking for writers 2026/02/18 04:51:09 DEBUG : file1: reading active writers 2026/02/18 04:51:09 DEBUG : >WaitForWriters: --- PASS: TestFileReadAtNonZeroLength (2.12s) FAIL 2026/02/18 04:51:10 DEBUG : Internxt root 'rclone-test-conusap3cife': Purge dir "" "./vfs.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestFileReadAtNonZeroLength|TestFileReadAtZeroLength)$|^TestDirReadDirAll$/^Virtual$'" - Finished ERROR in 23.116423262s (try 2/5): exit status 1: Failed [TestDirReadDirAll/Virtual]