"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirRemove|TestDirRemoveAll|TestFileOpenWrite)$|^TestDirReadDirAll$/^Virtual$'" - Starting (try 3/5) 2026/07/24 04:51:21 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-sacejew2dazo" 2026/07/24 04:51:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/24 04:51:21 DEBUG : Linkbox root 'rclone-test-sacejew2dazo': Using cached web token 2026/07/24 04:51:22 DEBUG : Creating backend with remote "/tmp/rclone4243202303" === RUN TestDirReadDirAll run.go:198: Remote "Linkbox root 'rclone-test-sacejew2dazo'", Local "Local file system at /tmp/rclone4243202303", Modify Window "876000h0m0s" 2026/07/24 04:51:22 INFO : Linkbox root 'rclone-test-sacejew2dazo': poll-interval is not supported by this remote 2026/07/24 04:51:22 NOTICE: Linkbox root 'rclone-test-sacejew2dazo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/07/24 04:51:27 DEBUG : dir/file1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:51:28 DEBUG : dir/file1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:51:30 DEBUG : dir/file2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:51:34 DEBUG : dir/subdir/file3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:51:35 DEBUG : dir/subdir/file3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:51:35 DEBUG : dir/subdir/file3: Trying to read object after upload: try again in 400ms (3/10) === RUN TestDirReadDirAll/Virtual 2026/07/24 04:51:37 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2026/07/24 04:51:37 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2026/07/24 04:51:37 DEBUG : dir: Added virtual directory entry vDel: "file2" 2026/07/24 04:51:37 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2026/07/24 04:51:39 DEBUG : dir/virtualFile: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:51:40 DEBUG : dir/virtualFile: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:51:40 DEBUG : dir/virtualFile: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:51:41 DEBUG : dir/virtualFile: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:51:45 DEBUG : dir/virtualDir/testFile: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:51:45 DEBUG : dir/virtualDir/testFile: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:51:46 DEBUG : dir/virtualDir/testFile: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:51:49 DEBUG : dir: invalidating directory cache 2026/07/24 04:51:50 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2026/07/24 04:51:50 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2026/07/24 04:51:50 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2026/07/24 04:51:50 DEBUG : dir/file1: Reset virtual modtime 2026/07/24 04:51:50 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2026/07/24 04:51:50 DEBUG : dir/virtualFile: Reset virtual modtime 2026/07/24 04:51:50 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2026/07/24 04:51:50 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2026/07/24 04:51:50 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/07/24 04:51:50 DEBUG : dir: invalidating directory cache 2026/07/24 04:51:50 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2" 2026/07/24 04:51:50 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2026/07/24 04:51:50 DEBUG : dir/virtualFile: Reset virtual modtime 2026/07/24 04:51:50 DEBUG : forgetting directory cache 2026/07/24 04:51:50 DEBUG : dir: forgetting directory cache 2026/07/24 04:51:50 DEBUG : dir/virtualDir: forgetting directory cache 2026/07/24 04:51:50 DEBUG : WaitForWriters: timeout=30s 2026/07/24 04:51:50 DEBUG : dir/virtualDir: Looking for writers 2026/07/24 04:51:50 DEBUG : dir: Looking for writers 2026/07/24 04:51:50 DEBUG : virtualFile: reading active writers 2026/07/24 04:51:50 DEBUG : virtualDir: reading active writers 2026/07/24 04:51:50 DEBUG : file1: reading active writers 2026/07/24 04:51:50 DEBUG : virtualFile2: reading active writers 2026/07/24 04:51:50 DEBUG : Looking for writers 2026/07/24 04:51:50 DEBUG : dir: reading active writers 2026/07/24 04:51:50 DEBUG : >WaitForWriters: === NAME TestDirReadDirAll run.go:186: removing dir "dir/virtualDir" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "dir/virtualDir" failed - try 2/3: sleeping 1.5s: directory not empty --- PASS: TestDirReadDirAll (38.59s) --- PASS: TestDirReadDirAll/Virtual (12.73s) === RUN TestDirRemove run.go:198: Remote "Linkbox root 'rclone-test-sacejew2dazo'", Local "Local file system at /tmp/rclone4243202303", Modify Window "876000h0m0s" 2026/07/24 04:52:01 INFO : Linkbox root 'rclone-test-sacejew2dazo': poll-interval is not supported by this remote 2026/07/24 04:52:01 NOTICE: Linkbox root 'rclone-test-sacejew2dazo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/07/24 04:52:05 DEBUG : dir/file1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:52:06 ERROR : dir/: Dir.Remove not empty 2026/07/24 04:52:06 DEBUG : dir/file1: Remove: 2026/07/24 04:52:07 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/07/24 04:52:07 DEBUG : dir/file1: >Remove: err= 2026/07/24 04:52:07 ERROR : dir/: Dir.Remove failed to remove directory: directory not empty dir_test.go:456: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:456 Error: Received unexpected error: directory not empty Test: TestDirRemove 2026/07/24 04:52:07 DEBUG : WaitForWriters: timeout=30s 2026/07/24 04:52:07 DEBUG : dir: Looking for writers 2026/07/24 04:52:07 DEBUG : Looking for writers 2026/07/24 04:52:07 DEBUG : dir: reading active writers 2026/07/24 04:52:07 DEBUG : >WaitForWriters: run.go:172: removing file "dir/file1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "dir/file1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "dir/file1" failed: could not Remove: Linkbox error 403: not permit --- FAIL: TestDirRemove (11.67s) === RUN TestDirRemoveAll run.go:198: Remote "Linkbox root 'rclone-test-sacejew2dazo'", Local "Local file system at /tmp/rclone4243202303", Modify Window "876000h0m0s" 2026/07/24 04:52:13 INFO : Linkbox root 'rclone-test-sacejew2dazo': poll-interval is not supported by this remote 2026/07/24 04:52:13 NOTICE: Linkbox root 'rclone-test-sacejew2dazo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/07/24 04:52:17 DEBUG : dir/file1: Remove: 2026/07/24 04:52:18 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/07/24 04:52:18 DEBUG : dir/file1: >Remove: err= 2026/07/24 04:52:19 ERROR : dir/: Dir.Remove failed to remove directory: directory not empty dir_test.go:481: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:481 Error: Received unexpected error: directory not empty Test: TestDirRemoveAll 2026/07/24 04:52:19 DEBUG : WaitForWriters: timeout=30s 2026/07/24 04:52:19 DEBUG : dir: Looking for writers 2026/07/24 04:52:19 DEBUG : Looking for writers 2026/07/24 04:52:19 DEBUG : dir: reading active writers 2026/07/24 04:52:19 DEBUG : >WaitForWriters: run.go:172: removing file "dir/file1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "dir/file1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "dir/file1" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestDirRemoveAll (18.95s) === RUN TestFileOpenWrite run.go:198: Remote "Linkbox root 'rclone-test-sacejew2dazo'", Local "Local file system at /tmp/rclone4243202303", Modify Window "876000h0m0s" 2026/07/24 04:52:31 INFO : Linkbox root 'rclone-test-sacejew2dazo': poll-interval is not supported by this remote 2026/07/24 04:52:31 NOTICE: Linkbox root 'rclone-test-sacejew2dazo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/07/24 04:52:35 DEBUG : dir/file1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:52:36 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/07/24 04:52:36 DEBUG : Linkbox root 'rclone-test-sacejew2dazo': File to upload is small (25 bytes), uploading instead of streaming 2026/07/24 04:52:36 DEBUG : dir/file1: Update: removing old file 2026/07/24 04:52:39 DEBUG : dir/file1: size = 25 OK 2026/07/24 04:52:39 NOTICE: Linkbox root 'rclone-test-sacejew2dazo': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/07/24 04:52:39 DEBUG : dir/file1: Size of src and dst objects identical 2026/07/24 04:52:39 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/07/24 04:52:39 DEBUG : WaitForWriters: timeout=30s 2026/07/24 04:52:39 DEBUG : dir: Looking for writers 2026/07/24 04:52:39 DEBUG : file1: reading active writers 2026/07/24 04:52:39 DEBUG : Looking for writers 2026/07/24 04:52:39 DEBUG : dir: reading active writers 2026/07/24 04:52:39 DEBUG : >WaitForWriters: run.go:186: removing dir "dir" failed - try 1/3: sleeping 1s: directory not empty --- PASS: TestFileOpenWrite (12.84s) FAIL 2026/07/24 04:52:44 DEBUG : Linkbox root 'rclone-test-sacejew2dazo': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirRemove|TestDirRemoveAll|TestFileOpenWrite)$|^TestDirReadDirAll$/^Virtual$'" - Finished ERROR in 1m24.260207659s (try 3/5): exit status 1: Failed [TestDirRemove TestDirRemoveAll]