"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirFileOpen|TestVFSMkdirAll)$'" - Starting (try 4/5)
2026/08/17 05:27:24 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-buramek8nuyi"
2026/08/17 05:27:24 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/17 05:27:24 DEBUG : Linkbox root 'rclone-test-buramek8nuyi': Using cached web token
2026/08/17 05:27:25 DEBUG : Creating backend with remote "/tmp/rclone1738318694"
=== RUN   TestDirFileOpen
    run.go:198: Remote "Linkbox root 'rclone-test-buramek8nuyi'", Local "Local file system at /tmp/rclone1738318694", Modify Window "876000h0m0s"
2026/08/17 05:27:25 INFO  : Linkbox root 'rclone-test-buramek8nuyi': poll-interval is not supported by this remote
2026/08/17 05:27:25 NOTICE: Linkbox root 'rclone-test-buramek8nuyi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/08/17 05:27:32 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2026/08/17 05:27:32 DEBUG : dir/sub/file0: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw-
2026/08/17 05:27:32 DEBUG : dir/sub/file0: Open: flags=O_RDWR|O_CREATE|O_TRUNC
2026/08/17 05:27:32 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0"
2026/08/17 05:27:32 DEBUG : dir/sub/file0: >Open: fd=dir/sub/file0 (w), err=<nil>
2026/08/17 05:27:32 DEBUG : dir/sub/file0: >OpenFile: fd=dir/sub/file0 (w), err=<nil>
2026/08/17 05:27:32 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0"
2026/08/17 05:27:32 DEBUG : dir/sub/file2: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw-
2026/08/17 05:27:32 DEBUG : dir/sub/file2: Open: flags=O_RDWR|O_CREATE|O_TRUNC
2026/08/17 05:27:32 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2"
2026/08/17 05:27:32 DEBUG : dir/sub/file2: >Open: fd=dir/sub/file2 (w), err=<nil>
2026/08/17 05:27:32 DEBUG : dir/sub/file2: >OpenFile: fd=dir/sub/file2 (w), err=<nil>
2026/08/17 05:27:32 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2"
2026/08/17 05:27:32 DEBUG : Linkbox root 'rclone-test-buramek8nuyi': File to upload is small (12 bytes), uploading instead of streaming
2026/08/17 05:27:34 DEBUG : dir/sub/file2: size = 12 OK
2026/08/17 05:27:34 NOTICE: Linkbox root 'rclone-test-buramek8nuyi': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/08/17 05:27:34 DEBUG : dir/sub/file2: Size of src and dst objects identical
2026/08/17 05:27:34 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2"
2026/08/17 05:27:34 DEBUG : forgetting directory cache
2026/08/17 05:27:34 DEBUG : dir: forgetting directory cache
2026/08/17 05:27:34 DEBUG : dir/sub: forgetting directory cache
2026/08/17 05:27:34 DEBUG : dir/sub: Removed virtual directory entry vAddFile: "file2"
2026/08/17 05:27:34 DEBUG : dir: Removed virtual directory entry vAddDir: "sub"
2026/08/17 05:27:34 DEBUG : Linkbox root 'rclone-test-buramek8nuyi': File to upload is small (5 bytes), uploading instead of streaming
2026/08/17 05:27:36 DEBUG : dir/sub/file0: size = 5 OK
2026/08/17 05:27:36 DEBUG : dir/sub/file0: Size of src and dst objects identical
2026/08/17 05:27:36 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0"
2026/08/17 05:27:36 DEBUG : WaitForWriters: timeout=30s
2026/08/17 05:27:36 DEBUG : dir/sub: Looking for writers
2026/08/17 05:27:36 DEBUG : file0: reading active writers
2026/08/17 05:27:36 DEBUG : file2: reading active writers
2026/08/17 05:27:36 DEBUG : dir: Looking for writers
2026/08/17 05:27:36 DEBUG : file1: reading active writers
2026/08/17 05:27:36 DEBUG : sub: reading active writers
2026/08/17 05:27:36 DEBUG : Looking for writers
2026/08/17 05:27:36 DEBUG : dir: reading active writers
2026/08/17 05:27:36 DEBUG : >WaitForWriters: 
2026/08/17 05:27:43 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:43 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/17 05:27:43 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:43 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/17 05:27:43 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:43 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/17 05:27:44 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:44 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/17 05:27:46 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:48 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:50 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:52 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:54 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:27:56 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:186: removing dir "dir" failed - try 1/3: sleeping 1s: getting files failed: invalid character '<' looking for beginning of value
2026/08/17 05:27:58 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:28:00 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:28:02 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/17 05:28:04 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/17 05:28:07 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/17 05:28:07 DEBUG : pacer: Reducing sleep to 843.75ms
--- PASS: TestDirFileOpen (42.49s)
=== RUN   TestVFSMkdirAll
    run.go:198: Remote "Linkbox root 'rclone-test-buramek8nuyi'", Local "Local file system at /tmp/rclone1738318694", Modify Window "876000h0m0s"
2026/08/17 05:28:07 INFO  : Linkbox root 'rclone-test-buramek8nuyi': poll-interval is not supported by this remote
2026/08/17 05:28:07 NOTICE: Linkbox root 'rclone-test-buramek8nuyi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/08/17 05:28:08 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/17 05:28:09 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/17 05:28:10 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/17 05:28:10 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/17 05:28:12 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/17 05:28:12 DEBUG : Added virtual directory entry vAddDir: "a"
2026/08/17 05:28:12 DEBUG : pacer: Reducing sleep to 200ms
2026/08/17 05:28:13 DEBUG : a: Added virtual directory entry vAddDir: "b"
2026/08/17 05:28:15 DEBUG : a/b: Added virtual directory entry vAddDir: "c"
2026/08/17 05:28:17 DEBUG : a/b/c: Added virtual directory entry vAddDir: "d"
2026/08/17 05:28:20 DEBUG : WaitForWriters: timeout=30s
2026/08/17 05:28:20 DEBUG : a/b/c/d: Looking for writers
2026/08/17 05:28:20 DEBUG : a/b/c: Looking for writers
2026/08/17 05:28:20 DEBUG : d: reading active writers
2026/08/17 05:28:20 DEBUG : a/b: Looking for writers
2026/08/17 05:28:20 DEBUG : c: reading active writers
2026/08/17 05:28:20 DEBUG : a: Looking for writers
2026/08/17 05:28:20 DEBUG : b: reading active writers
2026/08/17 05:28:20 DEBUG : Looking for writers
2026/08/17 05:28:20 DEBUG : a: reading active writers
2026/08/17 05:28:20 DEBUG : >WaitForWriters: 
--- PASS: TestVFSMkdirAll (20.41s)
PASS
2026/08/17 05:28:28 DEBUG : Linkbox root 'rclone-test-buramek8nuyi': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirFileOpen|TestVFSMkdirAll)$'" - Finished OK in 1m5.303870523s (try 4/5)