"./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestDirMethods|TestDirOpen|TestDirWalk|TestRWFileHandleMethodsRead)$'" - Starting (try 3/5)
2026/08/15 02:18:03 DEBUG : Creating backend with remote "TestDrime:rclone-test-cafurez0bobe"
2026/08/15 02:18:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/15 02:18:04 DEBUG : Creating backend with remote "/tmp/rclone1021382579"
=== RUN   TestDirMethods
    run.go:198: Remote "drime root 'rclone-test-cafurez0bobe'", Local "Local file system at /tmp/rclone1021382579", Modify Window "876000h0m0s"
2026/08/15 02:18:04 INFO  : drime root 'rclone-test-cafurez0bobe': poll-interval is not supported by this remote
2026/08/15 02:18:05 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:18:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:18:05 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:18:06 DEBUG : WaitForWriters: timeout=30s
2026/08/15 02:18:06 DEBUG : dir: Looking for writers
2026/08/15 02:18:06 DEBUG : Looking for writers
2026/08/15 02:18:06 DEBUG : dir: reading active writers
2026/08/15 02:18:06 DEBUG : >WaitForWriters: 
--- PASS: TestDirMethods (37.82s)
=== RUN   TestDirWalk
    run.go:198: Remote "drime root 'rclone-test-cafurez0bobe'", Local "Local file system at /tmp/rclone1021382579", Modify Window "876000h0m0s"
2026/08/15 02:18:42 INFO  : drime root 'rclone-test-cafurez0bobe': poll-interval is not supported by this remote
2026/08/15 02:18:42 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:18:42 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:18:42 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:18:42 DEBUG : pacer: low level retry 1/1 (error Error "Server Error")
2026/08/15 02:18:42 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    run.go:299: Retry Put of "dir/file1" to drime root 'rclone-test-cafurez0bobe': 1/10 (failed to upload file: Error "Server Error")
2026/08/15 02:18:44 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:18:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:18:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:18:46 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:18:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:18:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:18:50 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:18:51 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:18:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:18:52 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:18:52 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:18:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:18:53 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:18:53 DEBUG : forgetting directory cache
2026/08/15 02:18:53 DEBUG : dir: forgetting directory cache
2026/08/15 02:18:53 DEBUG : WaitForWriters: timeout=30s
2026/08/15 02:18:53 DEBUG : dir: Looking for writers
2026/08/15 02:18:53 DEBUG : fil/a/b: Looking for writers
2026/08/15 02:18:53 DEBUG : fil/a: Looking for writers
2026/08/15 02:18:53 DEBUG : b: reading active writers
2026/08/15 02:18:53 DEBUG : fil: Looking for writers
2026/08/15 02:18:53 DEBUG : a: reading active writers
2026/08/15 02:18:53 DEBUG : Looking for writers
2026/08/15 02:18:53 DEBUG : fil: reading active writers
2026/08/15 02:18:53 DEBUG : dir: reading active writers
2026/08/15 02:18:53 DEBUG : >WaitForWriters: 
2026/08/15 02:18:53 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:18:53 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:18:54 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:19:29 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:19:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:19:30 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:19:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:19:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:19:47 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestDirWalk (121.74s)
=== RUN   TestDirOpen
    run.go:198: Remote "drime root 'rclone-test-cafurez0bobe'", Local "Local file system at /tmp/rclone1021382579", Modify Window "876000h0m0s"
2026/08/15 02:20:43 INFO  : drime root 'rclone-test-cafurez0bobe': poll-interval is not supported by this remote
2026/08/15 02:20:45 ERROR : dir/: Can only open directories read only
2026/08/15 02:20:45 DEBUG : WaitForWriters: timeout=30s
2026/08/15 02:20:45 DEBUG : dir: Looking for writers
2026/08/15 02:20:45 DEBUG : Looking for writers
2026/08/15 02:20:45 DEBUG : dir: reading active writers
2026/08/15 02:20:45 DEBUG : >WaitForWriters: 
2026/08/15 02:20:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:20:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:20:45 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:21:03 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:21:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:21:04 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:21:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:21:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:21:22 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestDirOpen (38.67s)
=== RUN   TestRWFileHandleMethodsRead
    run.go:198: Remote "drime root 'rclone-test-cafurez0bobe'", Local "Local file system at /tmp/rclone1021382579", Modify Window "876000h0m0s"
2026/08/15 02:21:22 INFO  : drime root 'rclone-test-cafurez0bobe': poll-interval is not supported by this remote
2026/08/15 02:21:22 DEBUG : drime root 'rclone-test-cafurez0bobe': vfs cache: root is "/home/rclone/.cache/rclone"
2026/08/15 02:21:22 DEBUG : drime root 'rclone-test-cafurez0bobe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-cafurez0bobe"
2026/08/15 02:21:22 DEBUG : drime root 'rclone-test-cafurez0bobe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-cafurez0bobe"
2026/08/15 02:21:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-cafurez0bobe"
2026/08/15 02:21:22 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/08/15 02:21:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-cafurez0bobe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestDrime/rclone-test-cafurez0bobe"
2026/08/15 02:21:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-cafurez0bobe"
2026/08/15 02:21:22 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/08/15 02:21:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-cafurez0bobe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestDrime/rclone-test-cafurez0bobe"
2026/08/15 02:21:22 INFO  : drime root 'rclone-test-cafurez0bobe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2026/08/15 02:21:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:21:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:21:23 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/15 02:21:23 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/08/15 02:21:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/15 02:21:24 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/08/15 02:21:24 DEBUG : pacer: Reducing sleep to 40ms
    run.go:303: Failed to put "dir/file1" to "drime root 'rclone-test-cafurez0bobe'": failed to make directory: failed to create folder: Error "422 Unprocessable Entity (422): {\"message\":\"\",\"errors\":{\"name\":\"Folder with same name already exists.\"}}"
2026/08/15 02:21:24 DEBUG : WaitForWriters: timeout=30s
2026/08/15 02:21:24 DEBUG : Looking for writers
2026/08/15 02:21:24 DEBUG : >WaitForWriters: 
2026/08/15 02:21:24 DEBUG : drime root 'rclone-test-cafurez0bobe': vfs cache: cleaner exiting
2026/08/15 02:21:24 DEBUG : pacer: Reducing sleep to 20ms
2026/08/15 02:21:24 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:21:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:21:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:21:42 DEBUG : pacer: Reducing sleep to 10ms
2026/08/15 02:21:42 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/15 02:21:42 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/15 02:21:43 DEBUG : pacer: Reducing sleep to 10ms
--- FAIL: TestRWFileHandleMethodsRead (20.96s)
FAIL
2026/08/15 02:21:43 DEBUG : drime root 'rclone-test-cafurez0bobe': Purge remote
"./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestDirMethods|TestDirOpen|TestDirWalk|TestRWFileHandleMethodsRead)$'" - Finished ERROR in 3m57.704111002s (try 3/5): exit status 1: Failed [TestRWFileHandleMethodsRead]