"./vfs.test -test.v -test.timeout 1h0m0s -remote TestUptobox: -verbose -test.run '^(TestDirCreate|TestDirMethods|TestDirReadDirAll|TestFileOpenRead|TestFileRemove|TestRcGetVFS|TestVFSNew|TestVFSStat|TestWriteFileHandleFlush)$'" - Starting (try 2/5)
2022/01/12 06:16:05 DEBUG : Creating backend with remote "TestUptobox:rclone-test-jowatam3yigahit1rogecel1"
2022/01/12 06:16:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/01/12 06:16:06 DEBUG : Creating backend with remote "/tmp/rclone613676852"
=== RUN   TestDirMethods
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:16:06 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:16:06 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:16:11 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:16:11 DEBUG : dir: Looking for writers
2022/01/12 06:16:11 DEBUG : : Looking for writers
2022/01/12 06:16:11 DEBUG : dir: reading active writers
2022/01/12 06:16:11 DEBUG : >WaitForWriters: 
--- PASS: TestDirMethods (7.61s)
=== RUN   TestDirReadDirAll
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:16:14 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:16:14 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
=== RUN   TestDirReadDirAll/Virtual
2022/01/12 06:16:32 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile"
2022/01/12 06:16:32 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir"
2022/01/12 06:16:32 DEBUG : dir: Added virtual directory entry vDel: "file2"
2022/01/12 06:16:32 DEBUG : dir: Added virtual directory entry vDel: "subdir"
=== CONT  TestDirReadDirAll
    run.go:287: Failed to put "dir/virtualFile" to "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'": Upload: unexpected response
=== CONT  TestDirReadDirAll/Virtual
    testing.go:1169: test executed panic(nil) or runtime.Goexit: subtest may have called FailNow on a parent test
2022/01/12 06:16:43 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:16:43 DEBUG : dir/virtualDir: Looking for writers
2022/01/12 06:16:43 DEBUG : dir: Looking for writers
2022/01/12 06:16:43 DEBUG : file1: reading active writers
2022/01/12 06:16:43 DEBUG : virtualFile: reading active writers
2022/01/12 06:16:43 DEBUG : virtualDir: reading active writers
2022/01/12 06:16:43 DEBUG : : Looking for writers
2022/01/12 06:16:43 DEBUG : dir: reading active writers
2022/01/12 06:16:43 DEBUG : >WaitForWriters: 
--- FAIL: TestDirReadDirAll (33.25s)
    --- FAIL: TestDirReadDirAll/Virtual (11.24s)
=== RUN   TestDirCreate
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:16:47 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:16:47 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:16:55 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE
2022/01/12 06:16:55 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2022/01/12 06:16:55 DEBUG : dir/potato: >Open: fd=dir/potato (w), err=<nil>
2022/01/12 06:16:55 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2022/01/12 06:16:55 DEBUG : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': File to upload is small (5 bytes), uploading instead of streaming
2022/01/12 06:16:59 INFO  : dir/potato: Copied (new)
2022/01/12 06:16:59 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2022/01/12 06:16:59 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:16:59 DEBUG : dir: Looking for writers
2022/01/12 06:16:59 DEBUG : file1: reading active writers
2022/01/12 06:16:59 DEBUG : potato: reading active writers
2022/01/12 06:16:59 DEBUG : : Looking for writers
2022/01/12 06:16:59 DEBUG : dir: reading active writers
2022/01/12 06:16:59 DEBUG : >WaitForWriters: 
--- PASS: TestDirCreate (14.71s)
=== RUN   TestFileOpenRead
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:17:02 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:17:02 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:17:07 DEBUG : dir/file1: Open: flags=O_RDONLY
2022/01/12 06:17:07 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err=<nil>
2022/01/12 06:17:07 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728
2022/01/12 06:17:08 DEBUG : dir/file1: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728
2022/01/12 06:17:08 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:17:08 DEBUG : dir: Looking for writers
2022/01/12 06:17:08 DEBUG : file1: reading active writers
2022/01/12 06:17:08 DEBUG : : Looking for writers
2022/01/12 06:17:08 DEBUG : dir: reading active writers
2022/01/12 06:17:08 DEBUG : >WaitForWriters: 
--- PASS: TestFileOpenRead (8.40s)
=== RUN   TestFileRemove
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:17:10 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:17:10 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:17:15 DEBUG : dir/file1: Remove: 
2022/01/12 06:17:15 DEBUG : dir: Added virtual directory entry vDel: "file1"
2022/01/12 06:17:16 DEBUG : dir/file1: >Remove: err=<nil>
2022/01/12 06:17:17 DEBUG : dir/file1: Remove: 
2022/01/12 06:17:17 DEBUG : dir/file1: >Remove: err=Read only file system
2022/01/12 06:17:17 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:17:17 DEBUG : dir: Looking for writers
2022/01/12 06:17:17 DEBUG : : Looking for writers
2022/01/12 06:17:17 DEBUG : dir: reading active writers
2022/01/12 06:17:17 DEBUG : >WaitForWriters: 
--- PASS: TestFileRemove (8.41s)
=== RUN   TestRcGetVFS
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:17:19 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:17:19 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:17:19 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:17:19 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:17:19 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:17:19 DEBUG : : Looking for writers
2022/01/12 06:17:19 DEBUG : >WaitForWriters: 
--- PASS: TestRcGetVFS (0.80s)
=== RUN   TestVFSNew
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:17:19 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:17:19 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:17:19 DEBUG : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': Re-using VFS from active cache
2022/01/12 06:17:19 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:17:19 DEBUG : : Looking for writers
2022/01/12 06:17:19 DEBUG : >WaitForWriters: 
--- PASS: TestVFSNew (0.80s)
=== RUN   TestVFSStat
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:17:20 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:17:20 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:17:29 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:17:29 DEBUG : dir: Looking for writers
2022/01/12 06:17:29 DEBUG : file2: reading active writers
2022/01/12 06:17:29 DEBUG : : Looking for writers
2022/01/12 06:17:29 DEBUG : dir: reading active writers
2022/01/12 06:17:29 DEBUG : file1: reading active writers
2022/01/12 06:17:29 DEBUG : >WaitForWriters: 
--- PASS: TestVFSStat (11.33s)
=== RUN   TestWriteFileHandleFlush
    run.go:181: Remote "Uptobox root 'rclone-test-jowatam3yigahit1rogecel1'", Local "Local file system at /tmp/rclone613676852", Modify Window "876000h0m0s"
2022/01/12 06:17:32 INFO  : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': poll-interval is not supported by this remote
2022/01/12 06:17:32 NOTICE: Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2022/01/12 06:17:32 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2022/01/12 06:17:32 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2022/01/12 06:17:32 DEBUG : : Added virtual directory entry vAddFile: "file1"
2022/01/12 06:17:32 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2022/01/12 06:17:32 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2022/01/12 06:17:32 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions
2022/01/12 06:17:32 DEBUG : : Added virtual directory entry vAddFile: "file1"
2022/01/12 06:17:32 DEBUG : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': File to upload is small (5 bytes), uploading instead of streaming
2022/01/12 06:17:37 INFO  : file1: Copied (new)
2022/01/12 06:17:37 DEBUG : : Added virtual directory entry vAddFile: "file1"
2022/01/12 06:17:37 DEBUG : file1: WriteFileHandle.Flush nothing to do
2022/01/12 06:17:37 DEBUG : WaitForWriters: timeout=30s
2022/01/12 06:17:37 DEBUG : : Looking for writers
2022/01/12 06:17:37 DEBUG : file1: reading active writers
2022/01/12 06:17:37 DEBUG : >WaitForWriters: 
--- PASS: TestWriteFileHandleFlush (7.04s)
FAIL
2022/01/12 06:17:39 DEBUG : Uptobox root 'rclone-test-jowatam3yigahit1rogecel1': Purge dir ""
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestUptobox: -verbose -test.run '^(TestDirCreate|TestDirMethods|TestDirReadDirAll|TestFileOpenRead|TestFileRemove|TestRcGetVFS|TestVFSNew|TestVFSStat|TestWriteFileHandleFlush)$'" - Finished ERROR in 1m34.58190962s (try 2/5): exit status 1: Failed [TestDirReadDirAll/Virtual]