"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFileLu: -verbose -test.run '^(TestDirHandleMethods|TestVFSStat|TestWriteFileHandleMethods|TestZipManyFiles|TestZipManySubDirs)$'" - Starting (try 3/5) 2025/11/21 02:33:45 DEBUG : Creating backend with remote "TestFileLu:rclone-test-pibapab7bubu" 2025/11/21 02:33:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/21 02:33:45 DEBUG : Creating backend with remote "/tmp/rclone2697714900" === RUN TestDirHandleMethods run.go:185: Remote "FileLu root 'rclone-test-pibapab7bubu'", Local "Local file system at /tmp/rclone2697714900", Modify Window "876000h0m0s" 2025/11/21 02:33:45 INFO : FileLu root 'rclone-test-pibapab7bubu': poll-interval is not supported by this remote 2025/11/21 02:33:45 NOTICE: FileLu root 'rclone-test-pibapab7bubu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/21 02:33:46 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully created folder "rclone-test-pibapab7bubu" with ID 1119117 2025/11/21 02:33:47 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully created folder "rclone-test-pibapab7bubu/dir" with ID 1119118 2025/11/21 02:33:48 INFO : FileLu root 'rclone-test-pibapab7bubu': Put: Successfully uploaded new file "dir/file1" 2025/11/21 02:33:49 DEBUG : WaitForWriters: timeout=30s 2025/11/21 02:33:49 DEBUG : dir: Looking for writers 2025/11/21 02:33:49 DEBUG : Looking for writers 2025/11/21 02:33:49 DEBUG : dir: reading active writers 2025/11/21 02:33:49 DEBUG : >WaitForWriters: 2025/11/21 02:33:50 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully deleted file: /rclone-test-pibapab7bubu/dir/file1 2025/11/21 02:33:51 INFO : FileLu root 'rclone-test-pibapab7bubu': Rmdir: successfully deleted "/rclone-test-pibapab7bubu/dir" --- PASS: TestDirHandleMethods (5.88s) === RUN TestVFSStat run.go:185: Remote "FileLu root 'rclone-test-pibapab7bubu'", Local "Local file system at /tmp/rclone2697714900", Modify Window "876000h0m0s" 2025/11/21 02:33:51 INFO : FileLu root 'rclone-test-pibapab7bubu': poll-interval is not supported by this remote 2025/11/21 02:33:51 NOTICE: FileLu root 'rclone-test-pibapab7bubu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/21 02:33:53 INFO : FileLu root 'rclone-test-pibapab7bubu': Put: Successfully uploaded new file "file1" 2025/11/21 02:33:54 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully created folder "rclone-test-pibapab7bubu/dir" with ID 1119122 2025/11/21 02:33:56 INFO : FileLu root 'rclone-test-pibapab7bubu': Put: Successfully uploaded new file "dir/file2" 2025/11/21 02:33:57 DEBUG : WaitForWriters: timeout=30s 2025/11/21 02:33:57 DEBUG : dir: Looking for writers 2025/11/21 02:33:57 DEBUG : file2: reading active writers 2025/11/21 02:33:57 DEBUG : Looking for writers 2025/11/21 02:33:57 DEBUG : dir: reading active writers 2025/11/21 02:33:57 DEBUG : file1: reading active writers 2025/11/21 02:33:57 DEBUG : >WaitForWriters: 2025/11/21 02:33:58 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully deleted file: /rclone-test-pibapab7bubu/file1 2025/11/21 02:33:59 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully deleted file: /rclone-test-pibapab7bubu/dir/file2 2025/11/21 02:34:00 INFO : FileLu root 'rclone-test-pibapab7bubu': Rmdir: successfully deleted "/rclone-test-pibapab7bubu/dir" --- PASS: TestVFSStat (8.61s) === RUN TestWriteFileHandleMethods run.go:185: Remote "FileLu root 'rclone-test-pibapab7bubu'", Local "Local file system at /tmp/rclone2697714900", Modify Window "876000h0m0s" 2025/11/21 02:34:00 INFO : FileLu root 'rclone-test-pibapab7bubu': poll-interval is not supported by this remote 2025/11/21 02:34:00 NOTICE: FileLu root 'rclone-test-pibapab7bubu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/21 02:34:00 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/11/21 02:34:00 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/11/21 02:34:00 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/11/21 02:34:00 DEBUG : file1: >Open: fd=file1 (w), err= 2025/11/21 02:34:00 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/11/21 02:34:00 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/11/21 02:34:00 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2025/11/21 02:34:00 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2025/11/21 02:34:00 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2025/11/21 02:34:00 DEBUG : FileLu root 'rclone-test-pibapab7bubu': File to upload is small (5 bytes), uploading instead of streaming 2025/11/21 02:34:02 ERROR : file1: WriteFileHandle.New Rcat failed: failed to upload file: upload failed with status: failed while requesting fs.cgi: Wrong file server IP 2025/11/21 02:34:02 DEBUG : file1: Remove: 2025/11/21 02:34:02 DEBUG : Added virtual directory entry vDel: "file1" 2025/11/21 02:34:02 DEBUG : file1: >Remove: err= write_test.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:144 Error: Received unexpected error: failed to upload file: upload failed with status: failed while requesting fs.cgi: Wrong file server IP Test: TestWriteFileHandleMethods 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/write_test.go:153 Error: Not equal: expected: []string{"file1,5,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=13) "file1,5,false" -} +([]string) Test: TestWriteFileHandleMethods fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:157 Error: Should be true Test: TestWriteFileHandleMethods Messages: listing wrong, want file1 (5) got fstest.go:203: Not found "file1" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:157 Error: Not equal: expected: 0 actual : 1 Test: TestWriteFileHandleMethods Messages: 1 objects not found 2025/11/21 02:34:12 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/11/21 02:34:12 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/11/21 02:34:12 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/11/21 02:34:12 DEBUG : file1: >Open: fd=file1 (w), err= 2025/11/21 02:34:12 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/11/21 02:34:12 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/11/21 02:34:12 DEBUG : FileLu root 'rclone-test-pibapab7bubu': File to upload is small (0 bytes), uploading instead of streaming 2025/11/21 02:34:12 ERROR : file1: WriteFileHandle.New Rcat failed: can't upload empty files to this remote 2025/11/21 02:34:12 DEBUG : file1: Remove: 2025/11/21 02:34:12 DEBUG : Added virtual directory entry vDel: "file1" 2025/11/21 02:34:12 DEBUG : file1: >Remove: err= write_test.go:163: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:163 Error: Received unexpected error: can't upload empty files to this remote Test: TestWriteFileHandleMethods 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/write_test.go:164 Error: Not equal: expected: []string{"file1,5,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=13) "file1,5,false" -} +([]string) Test: TestWriteFileHandleMethods 2025/11/21 02:34:12 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/11/21 02:34:12 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/11/21 02:34:12 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/11/21 02:34:12 DEBUG : file1: >Open: fd=file1 (w), err= 2025/11/21 02:34:12 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/11/21 02:34:12 DEBUG : Added virtual directory entry vAddFile: "file1" write_test.go:171: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:171 Error: Not equal: expected: *errors.errorString(&errors.errorString{s:"permission denied"}) actual : () Test: TestWriteFileHandleMethods 2025/11/21 02:34:12 DEBUG : WaitForWriters: timeout=30s 2025/11/21 02:34:12 DEBUG : Looking for writers 2025/11/21 02:34:12 DEBUG : file1: reading active writers 2025/11/21 02:34:12 DEBUG : file1: active writers 1 2025/11/21 02:34:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms 2025/11/21 02:34:12 DEBUG : Looking for writers 2025/11/21 02:34:12 DEBUG : file1: reading active writers 2025/11/21 02:34:12 DEBUG : file1: active writers 1 2025/11/21 02:34:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms 2025/11/21 02:34:12 DEBUG : Looking for writers 2025/11/21 02:34:12 DEBUG : file1: reading active writers 2025/11/21 02:34:12 DEBUG : file1: active writers 1 2025/11/21 02:34:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms 2025/11/21 02:34:12 DEBUG : Looking for writers 2025/11/21 02:34:12 DEBUG : file1: reading active writers 2025/11/21 02:34:12 DEBUG : file1: active writers 1 2025/11/21 02:34:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms 2025/11/21 02:34:12 DEBUG : Looking for writers 2025/11/21 02:34:12 DEBUG : file1: reading active writers 2025/11/21 02:34:12 DEBUG : file1: active writers 1 2025/11/21 02:34:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms 2025/11/21 02:34:12 DEBUG : Looking for writers 2025/11/21 02:34:12 DEBUG : file1: reading active writers 2025/11/21 02:34:12 DEBUG : file1: active writers 1 2025/11/21 02:34:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms 2025/11/21 02:34:12 DEBUG : Looking for writers 2025/11/21 02:34:12 DEBUG : file1: reading active writers 2025/11/21 02:34:12 DEBUG : file1: active writers 1 2025/11/21 02:34:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms 2025/11/21 02:34:13 DEBUG : Looking for writers 2025/11/21 02:34:13 DEBUG : file1: reading active writers 2025/11/21 02:34:13 DEBUG : file1: active writers 1 2025/11/21 02:34:13 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:14 DEBUG : Looking for writers 2025/11/21 02:34:14 DEBUG : file1: reading active writers 2025/11/21 02:34:14 DEBUG : file1: active writers 1 2025/11/21 02:34:14 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:15 DEBUG : Looking for writers 2025/11/21 02:34:15 DEBUG : file1: reading active writers 2025/11/21 02:34:15 DEBUG : file1: active writers 1 2025/11/21 02:34:15 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:16 DEBUG : Looking for writers 2025/11/21 02:34:16 DEBUG : file1: reading active writers 2025/11/21 02:34:16 DEBUG : file1: active writers 1 2025/11/21 02:34:16 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:17 DEBUG : Looking for writers 2025/11/21 02:34:17 DEBUG : file1: reading active writers 2025/11/21 02:34:17 DEBUG : file1: active writers 1 2025/11/21 02:34:17 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:18 DEBUG : Looking for writers 2025/11/21 02:34:18 DEBUG : file1: reading active writers 2025/11/21 02:34:18 DEBUG : file1: active writers 1 2025/11/21 02:34:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:19 DEBUG : Looking for writers 2025/11/21 02:34:19 DEBUG : file1: reading active writers 2025/11/21 02:34:19 DEBUG : file1: active writers 1 2025/11/21 02:34:19 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:20 DEBUG : Looking for writers 2025/11/21 02:34:20 DEBUG : file1: reading active writers 2025/11/21 02:34:20 DEBUG : file1: active writers 1 2025/11/21 02:34:20 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:21 DEBUG : Looking for writers 2025/11/21 02:34:21 DEBUG : file1: reading active writers 2025/11/21 02:34:21 DEBUG : file1: active writers 1 2025/11/21 02:34:21 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:22 DEBUG : Looking for writers 2025/11/21 02:34:22 DEBUG : file1: reading active writers 2025/11/21 02:34:22 DEBUG : file1: active writers 1 2025/11/21 02:34:22 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:23 DEBUG : Looking for writers 2025/11/21 02:34:23 DEBUG : file1: reading active writers 2025/11/21 02:34:23 DEBUG : file1: active writers 1 2025/11/21 02:34:23 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:24 DEBUG : Looking for writers 2025/11/21 02:34:24 DEBUG : file1: reading active writers 2025/11/21 02:34:24 DEBUG : file1: active writers 1 2025/11/21 02:34:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:25 DEBUG : Looking for writers 2025/11/21 02:34:25 DEBUG : file1: reading active writers 2025/11/21 02:34:25 DEBUG : file1: active writers 1 2025/11/21 02:34:25 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:26 DEBUG : Looking for writers 2025/11/21 02:34:26 DEBUG : file1: reading active writers 2025/11/21 02:34:26 DEBUG : file1: active writers 1 2025/11/21 02:34:26 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:27 DEBUG : Looking for writers 2025/11/21 02:34:27 DEBUG : file1: reading active writers 2025/11/21 02:34:27 DEBUG : file1: active writers 1 2025/11/21 02:34:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:28 DEBUG : Looking for writers 2025/11/21 02:34:28 DEBUG : file1: reading active writers 2025/11/21 02:34:28 DEBUG : file1: active writers 1 2025/11/21 02:34:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:29 DEBUG : Looking for writers 2025/11/21 02:34:29 DEBUG : file1: reading active writers 2025/11/21 02:34:29 DEBUG : file1: active writers 1 2025/11/21 02:34:29 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:30 DEBUG : Looking for writers 2025/11/21 02:34:30 DEBUG : file1: reading active writers 2025/11/21 02:34:30 DEBUG : file1: active writers 1 2025/11/21 02:34:30 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:31 DEBUG : Looking for writers 2025/11/21 02:34:31 DEBUG : file1: reading active writers 2025/11/21 02:34:31 DEBUG : file1: active writers 1 2025/11/21 02:34:31 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:32 DEBUG : Looking for writers 2025/11/21 02:34:32 DEBUG : file1: reading active writers 2025/11/21 02:34:32 DEBUG : file1: active writers 1 2025/11/21 02:34:32 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:33 DEBUG : Looking for writers 2025/11/21 02:34:33 DEBUG : file1: reading active writers 2025/11/21 02:34:33 DEBUG : file1: active writers 1 2025/11/21 02:34:33 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:34 DEBUG : Looking for writers 2025/11/21 02:34:34 DEBUG : file1: reading active writers 2025/11/21 02:34:34 DEBUG : file1: active writers 1 2025/11/21 02:34:34 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:35 DEBUG : Looking for writers 2025/11/21 02:34:35 DEBUG : file1: reading active writers 2025/11/21 02:34:35 DEBUG : file1: active writers 1 2025/11/21 02:34:35 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:36 DEBUG : Looking for writers 2025/11/21 02:34:36 DEBUG : file1: reading active writers 2025/11/21 02:34:36 DEBUG : file1: active writers 1 2025/11/21 02:34:36 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:37 DEBUG : Looking for writers 2025/11/21 02:34:37 DEBUG : file1: reading active writers 2025/11/21 02:34:37 DEBUG : file1: active writers 1 2025/11/21 02:34:37 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:38 DEBUG : Looking for writers 2025/11/21 02:34:38 DEBUG : file1: reading active writers 2025/11/21 02:34:38 DEBUG : file1: active writers 1 2025/11/21 02:34:38 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:39 DEBUG : Looking for writers 2025/11/21 02:34:39 DEBUG : file1: reading active writers 2025/11/21 02:34:39 DEBUG : file1: active writers 1 2025/11/21 02:34:39 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:40 DEBUG : Looking for writers 2025/11/21 02:34:40 DEBUG : file1: reading active writers 2025/11/21 02:34:40 DEBUG : file1: active writers 1 2025/11/21 02:34:40 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:41 DEBUG : Looking for writers 2025/11/21 02:34:41 DEBUG : file1: reading active writers 2025/11/21 02:34:41 DEBUG : file1: active writers 1 2025/11/21 02:34:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2025/11/21 02:34:42 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s Cache: 2025/11/21 02:34:42 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleMethods (42.49s) === RUN TestZipManyFiles run.go:185: Remote "FileLu root 'rclone-test-pibapab7bubu'", Local "Local file system at /tmp/rclone2697714900", Modify Window "876000h0m0s" 2025/11/21 02:34:42 INFO : FileLu root 'rclone-test-pibapab7bubu': poll-interval is not supported by this remote 2025/11/21 02:34:42 NOTICE: FileLu root 'rclone-test-pibapab7bubu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/21 02:34:43 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully created folder "rclone-test-pibapab7bubu/flat" with ID 1119128 2025/11/21 02:34:45 INFO : FileLu root 'rclone-test-pibapab7bubu': Put: Successfully uploaded new file "flat/f000.txt" 2025/11/21 02:34:46 INFO : FileLu root 'rclone-test-pibapab7bubu': Put: Successfully uploaded new file "flat/f001.txt" run.go:290: Failed to put "flat/f002.txt" to "FileLu root 'rclone-test-pibapab7bubu'": failed to upload file: upload failed with status: failed while requesting fs.cgi: Wrong file server IP 2025/11/21 02:34:48 DEBUG : WaitForWriters: timeout=30s 2025/11/21 02:34:48 DEBUG : Looking for writers 2025/11/21 02:34:48 DEBUG : >WaitForWriters: 2025/11/21 02:34:49 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully deleted file: /rclone-test-pibapab7bubu/flat/f000.txt 2025/11/21 02:34:50 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully deleted file: /rclone-test-pibapab7bubu/flat/f001.txt 2025/11/21 02:34:51 INFO : FileLu root 'rclone-test-pibapab7bubu': Rmdir: successfully deleted "/rclone-test-pibapab7bubu/flat" --- FAIL: TestZipManyFiles (8.46s) === RUN TestZipManySubDirs run.go:185: Remote "FileLu root 'rclone-test-pibapab7bubu'", Local "Local file system at /tmp/rclone2697714900", Modify Window "876000h0m0s" 2025/11/21 02:34:51 INFO : FileLu root 'rclone-test-pibapab7bubu': poll-interval is not supported by this remote 2025/11/21 02:34:51 NOTICE: FileLu root 'rclone-test-pibapab7bubu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/21 02:34:52 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully created folder "rclone-test-pibapab7bubu/a" with ID 1119130 2025/11/21 02:34:53 INFO : FileLu root 'rclone-test-pibapab7bubu': Put: Successfully uploaded new file "a/top.txt" 2025/11/21 02:34:54 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully created folder "rclone-test-pibapab7bubu/a/b" with ID 1119132 2025/11/21 02:34:55 INFO : FileLu root 'rclone-test-pibapab7bubu': Put: Successfully uploaded new file "a/b/mid.txt" 2025/11/21 02:34:56 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully created folder "rclone-test-pibapab7bubu/a/b/c" with ID 1119133 2025/11/21 02:34:58 INFO : FileLu root 'rclone-test-pibapab7bubu': Put: Successfully uploaded new file "a/b/c/deep.txt" 2025/11/21 02:34:59 DEBUG : a/b/c/deep.txt: Open: flags=O_RDONLY 2025/11/21 02:34:59 DEBUG : a/b/c/deep.txt: >Open: fd=a/b/c/deep.txt (r), err= 2025/11/21 02:34:59 DEBUG : a/b/c/deep.txt: Set virtual modtime to 2025-11-21 02:34:59.927491467 +0000 UTC m=+74.006530200 2025/11/21 02:34:59 DEBUG : a/b/c/deep.txt: ChunkedReader.openRange at 0 length 134217728 2025/11/21 02:35:01 DEBUG : a/b/c/deep.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2025/11/21 02:35:01 DEBUG : a/b/mid.txt: Open: flags=O_RDONLY 2025/11/21 02:35:01 DEBUG : a/b/mid.txt: >Open: fd=a/b/mid.txt (r), err= 2025/11/21 02:35:01 DEBUG : a/b/mid.txt: Set virtual modtime to 2025-11-21 02:34:59.548715123 +0000 UTC m=+73.627753807 2025/11/21 02:35:01 DEBUG : a/b/mid.txt: ChunkedReader.openRange at 0 length 134217728 2025/11/21 02:35:02 DEBUG : a/b/mid.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2025/11/21 02:35:02 DEBUG : a/top.txt: Open: flags=O_RDONLY 2025/11/21 02:35:02 DEBUG : a/top.txt: >Open: fd=a/top.txt (r), err= 2025/11/21 02:35:02 DEBUG : a/top.txt: Set virtual modtime to 2025-11-21 02:34:59.183121424 +0000 UTC m=+73.262160108 2025/11/21 02:35:02 DEBUG : a/top.txt: ChunkedReader.openRange at 0 length 134217728 2025/11/21 02:35:03 DEBUG : a/top.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2025/11/21 02:35:03 DEBUG : WaitForWriters: timeout=30s 2025/11/21 02:35:03 DEBUG : a/b/c: Looking for writers 2025/11/21 02:35:03 DEBUG : deep.txt: reading active writers 2025/11/21 02:35:03 DEBUG : a/b: Looking for writers 2025/11/21 02:35:03 DEBUG : c: reading active writers 2025/11/21 02:35:03 DEBUG : mid.txt: reading active writers 2025/11/21 02:35:03 DEBUG : a: Looking for writers 2025/11/21 02:35:03 DEBUG : b: reading active writers 2025/11/21 02:35:03 DEBUG : top.txt: reading active writers 2025/11/21 02:35:03 DEBUG : Looking for writers 2025/11/21 02:35:03 DEBUG : a: reading active writers 2025/11/21 02:35:03 DEBUG : >WaitForWriters: 2025/11/21 02:35:06 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully deleted file: /rclone-test-pibapab7bubu/a/top.txt 2025/11/21 02:35:06 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully deleted file: /rclone-test-pibapab7bubu/a/b/mid.txt 2025/11/21 02:35:07 INFO : FileLu root 'rclone-test-pibapab7bubu': Successfully deleted file: /rclone-test-pibapab7bubu/a/b/c/deep.txt 2025/11/21 02:35:08 INFO : FileLu root 'rclone-test-pibapab7bubu': Rmdir: successfully deleted "/rclone-test-pibapab7bubu/a/b/c" 2025/11/21 02:35:09 INFO : FileLu root 'rclone-test-pibapab7bubu': Rmdir: successfully deleted "/rclone-test-pibapab7bubu/a/b" 2025/11/21 02:35:09 INFO : FileLu root 'rclone-test-pibapab7bubu': Rmdir: successfully deleted "/rclone-test-pibapab7bubu/a" --- PASS: TestZipManySubDirs (18.87s) FAIL 2025/11/21 02:35:10 DEBUG : FileLu root 'rclone-test-pibapab7bubu': Purge remote 2025/11/21 02:35:10 INFO : FileLu root 'rclone-test-pibapab7bubu': Rmdir: successfully deleted "/rclone-test-pibapab7bubu" "./vfs.test -test.v -test.timeout 1h0m0s -remote TestFileLu: -verbose -test.run '^(TestDirHandleMethods|TestVFSStat|TestWriteFileHandleMethods|TestZipManyFiles|TestZipManySubDirs)$'" - Finished ERROR in 1m24.746453468s (try 3/5): exit status 1: Failed [TestWriteFileHandleMethods TestZipManyFiles]