"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^TestFileRename$/^minimal,forceCache=false$'" - Starting (try 4/5)
2026/10/05 07:40:03 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-nuveboc2napa"
2026/10/05 07:40:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/05 07:40:03 DEBUG : Linkbox root 'rclone-test-nuveboc2napa': Using cached web token
2026/10/05 07:40:03 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:03 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/10/05 07:40:03 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:03 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/10/05 07:40:04 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:04 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/10/05 07:40:04 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:04 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/10/05 07:40:06 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:08 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:10 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:12 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:14 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:16 DEBUG : pacer: Reducing sleep to 1.5s
2026/10/05 07:40:18 DEBUG : pacer: Reducing sleep to 1.125s
2026/10/05 07:40:18 DEBUG : Creating backend with remote "/tmp/rclone384486864"
=== RUN   TestFileRename
=== RUN   TestFileRename/minimal,forceCache=false
    run.go:198: Remote "Linkbox root 'rclone-test-nuveboc2napa'", Local "Local file system at /tmp/rclone384486864", Modify Window "876000h0m0s"
2026/10/05 07:40:18 INFO  : Linkbox root 'rclone-test-nuveboc2napa': poll-interval is not supported by this remote
2026/10/05 07:40:18 NOTICE: Linkbox root 'rclone-test-nuveboc2napa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/10/05 07:40:18 DEBUG : Linkbox root 'rclone-test-nuveboc2napa': vfs cache: root is "/home/rclone/.cache/rclone"
2026/10/05 07:40:18 DEBUG : Linkbox root 'rclone-test-nuveboc2napa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-nuveboc2napa"
2026/10/05 07:40:18 DEBUG : Linkbox root 'rclone-test-nuveboc2napa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-nuveboc2napa"
2026/10/05 07:40:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-nuveboc2napa"
2026/10/05 07:40:18 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/10/05 07:40:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-nuveboc2napa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-nuveboc2napa"
2026/10/05 07:40:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-nuveboc2napa"
2026/10/05 07:40:18 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/10/05 07:40:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-nuveboc2napa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-nuveboc2napa"
2026/10/05 07:40:18 INFO  : Linkbox root 'rclone-test-nuveboc2napa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2026/10/05 07:40:20 DEBUG : pacer: Reducing sleep to 843.75ms
2026/10/05 07:40:22 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/10/05 07:40:22 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/10/05 07:40:24 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/10/05 07:40:24 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/10/05 07:40:25 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/10/05 07:40:26 DEBUG : pacer: Reducing sleep to 200ms
    file_test.go:322: skip as can't rename files
2026/10/05 07:40:27 DEBUG : WaitForWriters: timeout=30s
2026/10/05 07:40:27 DEBUG : dir: Looking for writers
2026/10/05 07:40:27 DEBUG : file1: reading active writers
2026/10/05 07:40:27 DEBUG : Looking for writers
2026/10/05 07:40:27 DEBUG : dir: reading active writers
2026/10/05 07:40:27 DEBUG : >WaitForWriters: 
2026/10/05 07:40:27 DEBUG : Linkbox root 'rclone-test-nuveboc2napa': vfs cache: cleaner exiting
--- PASS: TestFileRename (12.19s)
    --- SKIP: TestFileRename/minimal,forceCache=false (12.19s)
PASS
2026/10/05 07:40:31 DEBUG : Linkbox root 'rclone-test-nuveboc2napa': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^TestFileRename$/^minimal,forceCache=false$'" - Finished OK in 29.028469201s (try 4/5)