"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFileLu: -verbose -test.run '^(TestDirCreate|TestDirMkdirSub|TestFileMethods)$'" - Starting (try 2/5)
2026/01/11 03:00:10 DEBUG : Creating backend with remote "TestFileLu:rclone-test-siquweh7bosu"
2026/01/11 03:00:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/01/11 03:00:10 DEBUG : Creating backend with remote "/tmp/rclone4237258857"
=== RUN   TestDirCreate
    run.go:185: Remote "FileLu root 'rclone-test-siquweh7bosu'", Local "Local file system at /tmp/rclone4237258857", Modify Window "876000h0m0s"
2026/01/11 03:00:10 INFO  : FileLu root 'rclone-test-siquweh7bosu': poll-interval is not supported by this remote
2026/01/11 03:00:10 NOTICE: FileLu root 'rclone-test-siquweh7bosu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/01/11 03:00:11 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully created folder "rclone-test-siquweh7bosu" with ID 2416508
2026/01/11 03:00:11 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully created folder "rclone-test-siquweh7bosu/dir" with ID 2416509
2026/01/11 03:00:13 INFO  : FileLu root 'rclone-test-siquweh7bosu': Put: Successfully uploaded new file "dir/file1"
2026/01/11 03:00:14 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE
2026/01/11 03:00:14 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/01/11 03:00:14 DEBUG : dir/potato: >Open: fd=dir/potato (w), err=<nil>
2026/01/11 03:00:14 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/01/11 03:00:14 DEBUG : FileLu root 'rclone-test-siquweh7bosu': File to upload is small (5 bytes), uploading instead of streaming
2026/01/11 03:00:15 INFO  : FileLu root 'rclone-test-siquweh7bosu': Put: Successfully uploaded new file "dir/potato"
2026/01/11 03:00:15 DEBUG : dir/potato: size = 5 OK
2026/01/11 03:00:15 NOTICE: FileLu root 'rclone-test-siquweh7bosu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/01/11 03:00:15 DEBUG : dir/potato: Size of src and dst objects identical
2026/01/11 03:00:15 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/01/11 03:00:15 DEBUG : WaitForWriters: timeout=30s
2026/01/11 03:00:15 DEBUG : dir: Looking for writers
2026/01/11 03:00:15 DEBUG : file1: reading active writers
2026/01/11 03:00:15 DEBUG : potato: reading active writers
2026/01/11 03:00:15 DEBUG : Looking for writers
2026/01/11 03:00:15 DEBUG : dir: reading active writers
2026/01/11 03:00:15 DEBUG : >WaitForWriters: 
2026/01/11 03:00:16 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully deleted file: /rclone-test-siquweh7bosu/dir/file1
2026/01/11 03:00:17 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully deleted file: /rclone-test-siquweh7bosu/dir/potato
2026/01/11 03:00:17 INFO  : FileLu root 'rclone-test-siquweh7bosu': Rmdir: successfully deleted "/rclone-test-siquweh7bosu/dir"
--- PASS: TestDirCreate (7.22s)
=== RUN   TestDirMkdirSub
    run.go:185: Remote "FileLu root 'rclone-test-siquweh7bosu'", Local "Local file system at /tmp/rclone4237258857", Modify Window "876000h0m0s"
2026/01/11 03:00:17 INFO  : FileLu root 'rclone-test-siquweh7bosu': poll-interval is not supported by this remote
2026/01/11 03:00:17 NOTICE: FileLu root 'rclone-test-siquweh7bosu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/01/11 03:00:18 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully created folder "rclone-test-siquweh7bosu/dir" with ID 2416511
2026/01/11 03:00:19 INFO  : FileLu root 'rclone-test-siquweh7bosu': Put: Successfully uploaded new file "dir/file1"
2026/01/11 03:00:21 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully created folder "rclone-test-siquweh7bosu/dir/sub" with ID 2416513
2026/01/11 03:00:21 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2026/01/11 03:00:21 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully created folder "rclone-test-siquweh7bosu/dir/sub/subsub" with ID 2416514
2026/01/11 03:00:21 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub"
2026/01/11 03:00:23 DEBUG : WaitForWriters: timeout=30s
2026/01/11 03:00:23 DEBUG : dir/sub/subsub: Looking for writers
2026/01/11 03:00:23 DEBUG : dir/sub: Looking for writers
2026/01/11 03:00:23 DEBUG : subsub: reading active writers
2026/01/11 03:00:23 DEBUG : dir: Looking for writers
2026/01/11 03:00:23 DEBUG : file1: reading active writers
2026/01/11 03:00:23 DEBUG : sub: reading active writers
2026/01/11 03:00:23 DEBUG : Looking for writers
2026/01/11 03:00:23 DEBUG : dir: reading active writers
2026/01/11 03:00:23 DEBUG : >WaitForWriters: 
2026/01/11 03:00:23 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully deleted file: /rclone-test-siquweh7bosu/dir/file1
2026/01/11 03:00:25 INFO  : FileLu root 'rclone-test-siquweh7bosu': Rmdir: successfully deleted "/rclone-test-siquweh7bosu/dir/sub/subsub"
2026/01/11 03:00:25 INFO  : FileLu root 'rclone-test-siquweh7bosu': Rmdir: successfully deleted "/rclone-test-siquweh7bosu/dir/sub"
2026/01/11 03:00:26 INFO  : FileLu root 'rclone-test-siquweh7bosu': Rmdir: successfully deleted "/rclone-test-siquweh7bosu/dir"
--- PASS: TestDirMkdirSub (8.65s)
=== RUN   TestFileMethods
    run.go:185: Remote "FileLu root 'rclone-test-siquweh7bosu'", Local "Local file system at /tmp/rclone4237258857", Modify Window "876000h0m0s"
2026/01/11 03:00:26 INFO  : FileLu root 'rclone-test-siquweh7bosu': poll-interval is not supported by this remote
2026/01/11 03:00:26 NOTICE: FileLu root 'rclone-test-siquweh7bosu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/01/11 03:00:27 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully created folder "rclone-test-siquweh7bosu/dir" with ID 2416515
2026/01/11 03:00:28 INFO  : FileLu root 'rclone-test-siquweh7bosu': Put: Successfully uploaded new file "dir/file1"
2026/01/11 03:00:29 DEBUG : dir/file1: Set virtual modtime to 2026-01-11 03:00:29.49669859 +0000 UTC m=+18.892388128
2026/01/11 03:00:29 DEBUG : WaitForWriters: timeout=30s
2026/01/11 03:00:29 DEBUG : dir: Looking for writers
2026/01/11 03:00:29 DEBUG : file1: reading active writers
2026/01/11 03:00:29 DEBUG : Looking for writers
2026/01/11 03:00:29 DEBUG : dir: reading active writers
2026/01/11 03:00:29 DEBUG : >WaitForWriters: 
2026/01/11 03:00:30 INFO  : FileLu root 'rclone-test-siquweh7bosu': Successfully deleted file: /rclone-test-siquweh7bosu/dir/file1
2026/01/11 03:00:30 INFO  : FileLu root 'rclone-test-siquweh7bosu': Rmdir: successfully deleted "/rclone-test-siquweh7bosu/dir"
--- PASS: TestFileMethods (4.70s)
PASS
2026/01/11 03:00:31 DEBUG : FileLu root 'rclone-test-siquweh7bosu': Purge remote
2026/01/11 03:00:31 INFO  : FileLu root 'rclone-test-siquweh7bosu': Rmdir: successfully deleted "/rclone-test-siquweh7bosu"
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFileLu: -verbose -test.run '^(TestDirCreate|TestDirMkdirSub|TestFileMethods)$'" - Finished OK in 20.908163726s (try 2/5)