"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFileLu: -verbose -test.run '^(TestDirForgetAll|TestDirHandleReaddir|TestDirReadDirAll|TestDirWalk)$'" - Starting (try 2/5)
2025/09/05 01:50:53 DEBUG : Creating backend with remote "TestFileLu:rclone-test-yijohip9fuxa"
2025/09/05 01:50:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/05 01:50:53 DEBUG : Creating backend with remote "/tmp/rclone1628824893"
=== RUN   TestDirHandleReaddir
    run.go:180: Remote "FileLu root 'rclone-test-yijohip9fuxa'", Local "Local file system at /tmp/rclone1628824893", Modify Window "876000h0m0s"
2025/09/05 01:50:53 INFO  : FileLu root 'rclone-test-yijohip9fuxa': poll-interval is not supported by this remote
2025/09/05 01:50:53 NOTICE: FileLu root 'rclone-test-yijohip9fuxa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/09/05 01:50:54 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa" with ID 914705
2025/09/05 01:50:55 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa/dir" with ID 914706
2025/09/05 01:50:56 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/file1"
2025/09/05 01:50:58 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/file2"
2025/09/05 01:50:59 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa/dir/subdir" with ID 914707
2025/09/05 01:51:00 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/subdir/file3"
2025/09/05 01:51:02 DEBUG : WaitForWriters: timeout=30s
2025/09/05 01:51:02 DEBUG : dir/subdir: Looking for writers
2025/09/05 01:51:02 DEBUG : dir: Looking for writers
2025/09/05 01:51:02 DEBUG : file1: reading active writers
2025/09/05 01:51:02 DEBUG : file2: reading active writers
2025/09/05 01:51:02 DEBUG : subdir: reading active writers
2025/09/05 01:51:02 DEBUG : Looking for writers
2025/09/05 01:51:02 DEBUG : dir: reading active writers
2025/09/05 01:51:02 DEBUG : >WaitForWriters: 
2025/09/05 01:51:03 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/file1
2025/09/05 01:51:03 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/file2
2025/09/05 01:51:04 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/subdir/file3
2025/09/05 01:51:05 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/dir/subdir"
2025/09/05 01:51:06 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/dir"
--- PASS: TestDirHandleReaddir (12.82s)
=== RUN   TestDirForgetAll
    run.go:180: Remote "FileLu root 'rclone-test-yijohip9fuxa'", Local "Local file system at /tmp/rclone1628824893", Modify Window "876000h0m0s"
2025/09/05 01:51:06 INFO  : FileLu root 'rclone-test-yijohip9fuxa': poll-interval is not supported by this remote
2025/09/05 01:51:06 NOTICE: FileLu root 'rclone-test-yijohip9fuxa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/09/05 01:51:07 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa/dir" with ID 914708
2025/09/05 01:51:08 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/file1"
2025/09/05 01:51:10 DEBUG : dir: forgetting directory cache
2025/09/05 01:51:10 DEBUG : forgetting directory cache
2025/09/05 01:51:10 DEBUG : dir: forgetting directory cache
2025/09/05 01:51:10 DEBUG : WaitForWriters: timeout=30s
2025/09/05 01:51:10 DEBUG : Looking for writers
2025/09/05 01:51:10 DEBUG : >WaitForWriters: 
2025/09/05 01:51:11 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/file1
2025/09/05 01:51:12 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/dir"
--- PASS: TestDirForgetAll (6.04s)
=== RUN   TestDirWalk
    run.go:180: Remote "FileLu root 'rclone-test-yijohip9fuxa'", Local "Local file system at /tmp/rclone1628824893", Modify Window "876000h0m0s"
2025/09/05 01:51:12 INFO  : FileLu root 'rclone-test-yijohip9fuxa': poll-interval is not supported by this remote
2025/09/05 01:51:12 NOTICE: FileLu root 'rclone-test-yijohip9fuxa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/09/05 01:51:13 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa/dir" with ID 914709
2025/09/05 01:51:15 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/file1"
2025/09/05 01:51:16 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa/fil/a/b" with ID 914712
2025/09/05 01:51:18 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "fil/a/b/c"
2025/09/05 01:51:19 DEBUG : forgetting directory cache
2025/09/05 01:51:19 DEBUG : dir: forgetting directory cache
2025/09/05 01:51:20 DEBUG : WaitForWriters: timeout=30s
2025/09/05 01:51:20 DEBUG : dir: Looking for writers
2025/09/05 01:51:20 DEBUG : fil/a/b: Looking for writers
2025/09/05 01:51:20 DEBUG : fil/a: Looking for writers
2025/09/05 01:51:20 DEBUG : b: reading active writers
2025/09/05 01:51:20 DEBUG : fil: Looking for writers
2025/09/05 01:51:20 DEBUG : a: reading active writers
2025/09/05 01:51:20 DEBUG : Looking for writers
2025/09/05 01:51:20 DEBUG : dir: reading active writers
2025/09/05 01:51:20 DEBUG : fil: reading active writers
2025/09/05 01:51:20 DEBUG : >WaitForWriters: 
2025/09/05 01:51:21 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/file1
2025/09/05 01:51:22 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/fil/a/b/c
2025/09/05 01:51:23 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/fil/a/b"
2025/09/05 01:51:24 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/fil/a"
2025/09/05 01:51:24 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/fil"
2025/09/05 01:51:25 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/dir"
--- PASS: TestDirWalk (13.35s)
=== RUN   TestDirReadDirAll
    run.go:180: Remote "FileLu root 'rclone-test-yijohip9fuxa'", Local "Local file system at /tmp/rclone1628824893", Modify Window "876000h0m0s"
2025/09/05 01:51:25 INFO  : FileLu root 'rclone-test-yijohip9fuxa': poll-interval is not supported by this remote
2025/09/05 01:51:25 NOTICE: FileLu root 'rclone-test-yijohip9fuxa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/09/05 01:51:26 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa/dir" with ID 914713
2025/09/05 01:51:28 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/file1"
2025/09/05 01:51:30 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/file2"
2025/09/05 01:51:31 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa/dir/subdir" with ID 914714
2025/09/05 01:51:32 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/subdir/file3"
=== RUN   TestDirReadDirAll/Virtual
2025/09/05 01:51:34 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile"
2025/09/05 01:51:34 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir"
2025/09/05 01:51:34 DEBUG : dir: Added virtual directory entry vDel: "file2"
2025/09/05 01:51:34 DEBUG : dir: Added virtual directory entry vDel: "subdir"
2025/09/05 01:51:36 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/virtualFile"
2025/09/05 01:51:37 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully created folder "rclone-test-yijohip9fuxa/dir/virtualDir" with ID 914715
2025/09/05 01:51:39 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Put: Successfully uploaded new file "dir/virtualDir/testFile"
2025/09/05 01:51:40 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/file2
2025/09/05 01:51:40 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/dir/subdir"
2025/09/05 01:51:40 DEBUG : dir: invalidating directory cache
2025/09/05 01:51:40 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir"
2025/09/05 01:51:40 DEBUG : dir: Removed virtual directory entry vDel: "file2"
2025/09/05 01:51:40 DEBUG : dir: Removed virtual directory entry vDel: "subdir"
2025/09/05 01:51:40 DEBUG : dir/file1: Reset virtual modtime
2025/09/05 01:51:40 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile"
2025/09/05 01:51:40 DEBUG : dir/virtualFile: Reset virtual modtime
2025/09/05 01:51:40 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2"
2025/09/05 01:51:40 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2"
2025/09/05 01:51:40 DEBUG : dir: Added virtual directory entry vDel: "file1"
2025/09/05 01:51:40 DEBUG : dir: invalidating directory cache
2025/09/05 01:51:41 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2"
2025/09/05 01:51:41 DEBUG : dir: Removed virtual directory entry vDel: "file1"
2025/09/05 01:51:41 DEBUG : dir/virtualFile: Reset virtual modtime
2025/09/05 01:51:41 DEBUG : forgetting directory cache
2025/09/05 01:51:41 DEBUG : dir: forgetting directory cache
2025/09/05 01:51:41 DEBUG : dir/virtualDir: forgetting directory cache
2025/09/05 01:51:41 DEBUG : WaitForWriters: timeout=30s
2025/09/05 01:51:41 DEBUG : dir/virtualDir: Looking for writers
2025/09/05 01:51:41 DEBUG : dir: Looking for writers
2025/09/05 01:51:41 DEBUG : file1: reading active writers
2025/09/05 01:51:41 DEBUG : virtualFile2: reading active writers
2025/09/05 01:51:41 DEBUG : virtualFile: reading active writers
2025/09/05 01:51:41 DEBUG : virtualDir: reading active writers
2025/09/05 01:51:41 DEBUG : Looking for writers
2025/09/05 01:51:41 DEBUG : dir: reading active writers
2025/09/05 01:51:41 DEBUG : >WaitForWriters: 
2025/09/05 01:51:42 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/file1
2025/09/05 01:51:42 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/virtualFile
2025/09/05 01:51:43 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Successfully deleted file: /rclone-test-yijohip9fuxa/dir/virtualDir/testFile
2025/09/05 01:51:44 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/dir/virtualDir"
2025/09/05 01:51:44 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa/dir"
--- PASS: TestDirReadDirAll (19.47s)
    --- PASS: TestDirReadDirAll/Virtual (6.43s)
PASS
2025/09/05 01:51:45 DEBUG : FileLu root 'rclone-test-yijohip9fuxa': Purge remote
2025/09/05 01:51:45 INFO  : FileLu root 'rclone-test-yijohip9fuxa': Rmdir: successfully deleted "/rclone-test-yijohip9fuxa"
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFileLu: -verbose -test.run '^(TestDirForgetAll|TestDirHandleReaddir|TestDirReadDirAll|TestDirWalk)$'" - Finished OK in 52.109794188s (try 2/5)