"./vfs.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestDirRemoveAll|TestDirWalk|TestWriteFileHandleMethods)$|^TestDirReadDirAll$/^Virtual$'" - Starting (try 2/5)
2026/02/23 05:18:38 DEBUG : Creating backend with remote "TestInternxt:rclone-test-sekehug1hupa"
2026/02/23 05:18:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/23 05:18:38 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/23 05:18:39 DEBUG : Creating backend with remote "/tmp/rclone3527884681"
=== RUN   TestDirWalk
    run.go:185: Remote "Internxt root 'rclone-test-sekehug1hupa'", Local "Local file system at /tmp/rclone3527884681", Modify Window "876000h0m0s"
2026/02/23 05:18:39 INFO  : Internxt root 'rclone-test-sekehug1hupa': poll-interval is not supported by this remote
2026/02/23 05:18:39 NOTICE: Internxt root 'rclone-test-sekehug1hupa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/23 05:18:43 DEBUG : forgetting directory cache
2026/02/23 05:18:43 DEBUG : dir: forgetting directory cache
2026/02/23 05:18:44 DEBUG : WaitForWriters: timeout=30s
2026/02/23 05:18:44 DEBUG : dir: Looking for writers
2026/02/23 05:18:44 DEBUG : fil/a/b: Looking for writers
2026/02/23 05:18:44 DEBUG : fil/a: Looking for writers
2026/02/23 05:18:44 DEBUG : b: reading active writers
2026/02/23 05:18:44 DEBUG : fil: Looking for writers
2026/02/23 05:18:44 DEBUG : a: reading active writers
2026/02/23 05:18:44 DEBUG : Looking for writers
2026/02/23 05:18:44 DEBUG : fil: reading active writers
2026/02/23 05:18:44 DEBUG : dir: reading active writers
2026/02/23 05:18:44 DEBUG : >WaitForWriters: 
    run.go:130: removing dir "fil/a" failed - try 1/5: directory not empty
    run.go:130: removing dir "fil" failed - try 1/5: directory not empty
--- PASS: TestDirWalk (10.81s)
=== RUN   TestDirReadDirAll
    run.go:185: Remote "Internxt root 'rclone-test-sekehug1hupa'", Local "Local file system at /tmp/rclone3527884681", Modify Window "876000h0m0s"
2026/02/23 05:18:50 INFO  : Internxt root 'rclone-test-sekehug1hupa': poll-interval is not supported by this remote
2026/02/23 05:18:50 NOTICE: Internxt root 'rclone-test-sekehug1hupa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
=== RUN   TestDirReadDirAll/Virtual
2026/02/23 05:18:54 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile"
2026/02/23 05:18:54 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir"
2026/02/23 05:18:54 DEBUG : dir: Added virtual directory entry vDel: "file2"
2026/02/23 05:18:54 DEBUG : dir: Added virtual directory entry vDel: "subdir"
2026/02/23 05:18:56 DEBUG : Waiting for deletions to finish
2026/02/23 05:18:57 INFO  : dir/subdir/file3: Deleted
2026/02/23 05:18:57 DEBUG : removing 1 level 2 directories
2026/02/23 05:18:57 INFO  : dir/subdir: Removing directory
2026/02/23 05:18:57 DEBUG : dir: invalidating directory cache
2026/02/23 05:18:57 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir"
2026/02/23 05:18:57 DEBUG : dir: Removed virtual directory entry vDel: "file2"
2026/02/23 05:18:57 DEBUG : dir: Removed virtual directory entry vDel: "subdir"
2026/02/23 05:18:57 DEBUG : dir/file1: Reset virtual modtime
2026/02/23 05:18:57 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile"
2026/02/23 05:18:57 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/23 05:18:57 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2"
2026/02/23 05:18:57 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2"
2026/02/23 05:18:57 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/23 05:18:57 DEBUG : dir: invalidating directory cache
2026/02/23 05:18:58 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2"
2026/02/23 05:18:58 DEBUG : dir: Removed virtual directory entry vDel: "file1"
2026/02/23 05:18:58 DEBUG : dir/virtualFile: Reset virtual modtime
2026/02/23 05:18:58 DEBUG : forgetting directory cache
2026/02/23 05:18:58 DEBUG : dir: forgetting directory cache
2026/02/23 05:18:58 DEBUG : dir/virtualDir: forgetting directory cache
2026/02/23 05:18:58 DEBUG : WaitForWriters: timeout=30s
2026/02/23 05:18:58 DEBUG : dir/virtualDir: Looking for writers
2026/02/23 05:18:58 DEBUG : dir: Looking for writers
2026/02/23 05:18:58 DEBUG : file1: reading active writers
2026/02/23 05:18:58 DEBUG : virtualFile2: reading active writers
2026/02/23 05:18:58 DEBUG : virtualFile: reading active writers
2026/02/23 05:18:58 DEBUG : virtualDir: reading active writers
2026/02/23 05:18:58 DEBUG : Looking for writers
2026/02/23 05:18:58 DEBUG : dir: reading active writers
2026/02/23 05:18:58 DEBUG : >WaitForWriters: 
=== NAME  TestDirReadDirAll
    run.go:130: removing dir "dir" failed - try 1/5: directory not empty
2026/02/23 05:19:01 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.39s)
    --- FAIL: TestDirReadDirAll/Virtual (3.47s)
=== RUN   TestDirRemoveAll
    run.go:185: Remote "Internxt root 'rclone-test-sekehug1hupa'", Local "Local file system at /tmp/rclone3527884681", Modify Window "876000h0m0s"
2026/02/23 05:19:08 INFO  : Internxt root 'rclone-test-sekehug1hupa': poll-interval is not supported by this remote
2026/02/23 05:19:08 NOTICE: Internxt root 'rclone-test-sekehug1hupa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/23 05:19:10 DEBUG : dir/file1: Remove: 
2026/02/23 05:19:10 DEBUG : dir: Added virtual directory entry vDel: "file1"
2026/02/23 05:19:10 DEBUG : dir/file1: >Remove: err=<nil>
2026/02/23 05:19:11 DEBUG : Added virtual directory entry vDel: "dir"
2026/02/23 05:19: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
2026/02/23 05:19:18 DEBUG : WaitForWriters: timeout=30s
2026/02/23 05:19:18 DEBUG : Looking for writers
2026/02/23 05:19:18 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemoveAll (10.34s)
=== RUN   TestWriteFileHandleMethods
    run.go:185: Remote "Internxt root 'rclone-test-sekehug1hupa'", Local "Local file system at /tmp/rclone3527884681", Modify Window "876000h0m0s"
2026/02/23 05:19:18 INFO  : Internxt root 'rclone-test-sekehug1hupa': poll-interval is not supported by this remote
2026/02/23 05:19:18 NOTICE: Internxt root 'rclone-test-sekehug1hupa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/23 05:19:18 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2026/02/23 05:19:19 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2026/02/23 05:19:19 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:19 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/02/23 05:19:19 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/02/23 05:19:19 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:19 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal
2026/02/23 05:19:19 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal
2026/02/23 05:19:19 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes
2026/02/23 05:19:19 DEBUG : Internxt root 'rclone-test-sekehug1hupa': File to upload is small (5 bytes), uploading instead of streaming
2026/02/23 05:19:19 DEBUG : file1: size = 5 OK
2026/02/23 05:19:19 NOTICE: Internxt root 'rclone-test-sekehug1hupa': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/02/23 05:19:19 DEBUG : file1: Size of src and dst objects identical
2026/02/23 05:19:19 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:20 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2026/02/23 05:19:20 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2026/02/23 05:19:20 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:20 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/02/23 05:19:20 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/02/23 05:19:20 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2026/02/23 05:19:20 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2026/02/23 05:19:20 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:20 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/02/23 05:19:20 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/02/23 05:19:20 ERROR : file1: WriteFileHandle: Can't open for write without O_TRUNC on existing file without --vfs-cache-mode >= writes
2026/02/23 05:19:20 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2026/02/23 05:19:20 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2026/02/23 05:19:20 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:20 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/02/23 05:19:20 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/02/23 05:19:20 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:20 DEBUG : Internxt root 'rclone-test-sekehug1hupa': File to upload is small (0 bytes), uploading instead of streaming
2026/02/23 05:19:20 DEBUG : Internxt root 'rclone-test-sekehug1hupa': Renamed existing file file1 to backup file1.rclone-backup-pejifak2. (UUID: dc4b4376-f2c3-4338-b6a2-48f709bd0f65)
2026/02/23 05:19:23 DEBUG : Internxt root 'rclone-test-sekehug1hupa': Upload succeeded, deleting backup file file1.rclone-backup-pejifak2. (UUID: dc4b4376-f2c3-4338-b6a2-48f709bd0f65)
2026/02/23 05:19:23 DEBUG : Internxt root 'rclone-test-sekehug1hupa': Successfully deleted backup file
2026/02/23 05:19:23 DEBUG : file1: size = 0 OK
2026/02/23 05:19:23 DEBUG : file1: Size of src and dst objects identical
2026/02/23 05:19:23 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:23 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2026/02/23 05:19:23 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2026/02/23 05:19:23 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:23 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/02/23 05:19:23 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/02/23 05:19:23 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:23 DEBUG : Internxt root 'rclone-test-sekehug1hupa': File to upload is small (7 bytes), uploading instead of streaming
2026/02/23 05:19:24 DEBUG : Internxt root 'rclone-test-sekehug1hupa': Renamed existing file file1 to backup file1.rclone-backup-ziqizof8. (UUID: 7b761fb3-87f3-4169-b4df-57eaa013fc06)
2026/02/23 05:19:24 DEBUG : Internxt root 'rclone-test-sekehug1hupa': Upload succeeded, deleting backup file file1.rclone-backup-ziqizof8. (UUID: 7b761fb3-87f3-4169-b4df-57eaa013fc06)
2026/02/23 05:19:25 DEBUG : Internxt root 'rclone-test-sekehug1hupa': Successfully deleted backup file
2026/02/23 05:19:25 DEBUG : file1: size = 7 OK
2026/02/23 05:19:25 DEBUG : file1: Size of src and dst objects identical
2026/02/23 05:19:25 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/02/23 05:19:25 DEBUG : WaitForWriters: timeout=30s
2026/02/23 05:19:25 DEBUG : Looking for writers
2026/02/23 05:19:25 DEBUG : file1: reading active writers
2026/02/23 05:19:25 DEBUG : >WaitForWriters: 
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestWriteFileHandleMethods (14.10s)
FAIL
2026/02/23 05:19:33 DEBUG : Internxt root 'rclone-test-sekehug1hupa': Purge dir ""
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestDirRemoveAll|TestDirWalk|TestWriteFileHandleMethods)$|^TestDirReadDirAll$/^Virtual$'" - Finished ERROR in 55.270570255s (try 2/5): exit status 1: Failed [TestDirReadDirAll/Virtual]