"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^TestWriteFileModTimeWithOpenWriters$'" - Starting (try 3/5)
2026/09/02 03:19:01 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-fojigox8wuno"
2026/09/02 03:19:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/02 03:19:01 DEBUG : Creating backend with remote "/tmp/rclone2904751715"
=== RUN   TestWriteFileModTimeWithOpenWriters
    run.go:198: Remote "premiumize.me root 'rclone-test-fojigox8wuno'", Local "Local file system at /tmp/rclone2904751715", Modify Window "876000h0m0s"
2026/09/02 03:19:01 INFO  : premiumize.me root 'rclone-test-fojigox8wuno': poll-interval is not supported by this remote
2026/09/02 03:19:01 NOTICE: premiumize.me root 'rclone-test-fojigox8wuno': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/09/02 03:19:01 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2026/09/02 03:19:01 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2026/09/02 03:19:01 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/02 03:19:01 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/09/02 03:19:01 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/09/02 03:19:02 DEBUG : Can set mod time: false
    write_test.go:363: can't set mod time
2026/09/02 03:19:02 DEBUG : WaitForWriters: timeout=30s
2026/09/02 03:19:02 DEBUG : Looking for writers
2026/09/02 03:19:02 DEBUG : file1: reading active writers
2026/09/02 03:19:02 DEBUG : file1: active writers 1
2026/09/02 03:19:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms
2026/09/02 03:19:02 DEBUG : Looking for writers
2026/09/02 03:19:02 DEBUG : file1: reading active writers
2026/09/02 03:19:02 DEBUG : file1: active writers 1
2026/09/02 03:19:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms
2026/09/02 03:19:02 DEBUG : Looking for writers
2026/09/02 03:19:02 DEBUG : file1: reading active writers
2026/09/02 03:19:02 DEBUG : file1: active writers 1
2026/09/02 03:19:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms
2026/09/02 03:19:02 DEBUG : Looking for writers
2026/09/02 03:19:02 DEBUG : file1: reading active writers
2026/09/02 03:19:02 DEBUG : file1: active writers 1
2026/09/02 03:19:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms
2026/09/02 03:19:02 DEBUG : Looking for writers
2026/09/02 03:19:02 DEBUG : file1: reading active writers
2026/09/02 03:19:02 DEBUG : file1: active writers 1
2026/09/02 03:19:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms
2026/09/02 03:19:02 DEBUG : Looking for writers
2026/09/02 03:19:02 DEBUG : file1: reading active writers
2026/09/02 03:19:02 DEBUG : file1: active writers 1
2026/09/02 03:19:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms
2026/09/02 03:19:02 DEBUG : Looking for writers
2026/09/02 03:19:02 DEBUG : file1: reading active writers
2026/09/02 03:19:02 DEBUG : file1: active writers 1
2026/09/02 03:19:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms
2026/09/02 03:19:03 DEBUG : Looking for writers
2026/09/02 03:19:03 DEBUG : file1: reading active writers
2026/09/02 03:19:03 DEBUG : file1: active writers 1
2026/09/02 03:19:03 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:04 DEBUG : Looking for writers
2026/09/02 03:19:04 DEBUG : file1: reading active writers
2026/09/02 03:19:04 DEBUG : file1: active writers 1
2026/09/02 03:19:04 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:05 DEBUG : Looking for writers
2026/09/02 03:19:05 DEBUG : file1: reading active writers
2026/09/02 03:19:05 DEBUG : file1: active writers 1
2026/09/02 03:19:05 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:06 DEBUG : Looking for writers
2026/09/02 03:19:06 DEBUG : file1: reading active writers
2026/09/02 03:19:06 DEBUG : file1: active writers 1
2026/09/02 03:19:06 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:07 DEBUG : Looking for writers
2026/09/02 03:19:07 DEBUG : file1: reading active writers
2026/09/02 03:19:07 DEBUG : file1: active writers 1
2026/09/02 03:19:07 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:08 DEBUG : Looking for writers
2026/09/02 03:19:08 DEBUG : file1: reading active writers
2026/09/02 03:19:08 DEBUG : file1: active writers 1
2026/09/02 03:19:08 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:09 DEBUG : Looking for writers
2026/09/02 03:19:09 DEBUG : file1: reading active writers
2026/09/02 03:19:09 DEBUG : file1: active writers 1
2026/09/02 03:19:09 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:10 DEBUG : Looking for writers
2026/09/02 03:19:10 DEBUG : file1: reading active writers
2026/09/02 03:19:10 DEBUG : file1: active writers 1
2026/09/02 03:19:10 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:11 DEBUG : Looking for writers
2026/09/02 03:19:11 DEBUG : file1: reading active writers
2026/09/02 03:19:11 DEBUG : file1: active writers 1
2026/09/02 03:19:11 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:12 DEBUG : Looking for writers
2026/09/02 03:19:12 DEBUG : file1: reading active writers
2026/09/02 03:19:12 DEBUG : file1: active writers 1
2026/09/02 03:19:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:13 DEBUG : Looking for writers
2026/09/02 03:19:13 DEBUG : file1: reading active writers
2026/09/02 03:19:13 DEBUG : file1: active writers 1
2026/09/02 03:19:13 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:14 DEBUG : Looking for writers
2026/09/02 03:19:14 DEBUG : file1: reading active writers
2026/09/02 03:19:14 DEBUG : file1: active writers 1
2026/09/02 03:19:14 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:15 DEBUG : Looking for writers
2026/09/02 03:19:15 DEBUG : file1: reading active writers
2026/09/02 03:19:15 DEBUG : file1: active writers 1
2026/09/02 03:19:15 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:16 DEBUG : Looking for writers
2026/09/02 03:19:16 DEBUG : file1: reading active writers
2026/09/02 03:19:16 DEBUG : file1: active writers 1
2026/09/02 03:19:16 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:17 DEBUG : Looking for writers
2026/09/02 03:19:17 DEBUG : file1: reading active writers
2026/09/02 03:19:17 DEBUG : file1: active writers 1
2026/09/02 03:19:17 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:18 DEBUG : Looking for writers
2026/09/02 03:19:18 DEBUG : file1: reading active writers
2026/09/02 03:19:18 DEBUG : file1: active writers 1
2026/09/02 03:19:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:19 DEBUG : Looking for writers
2026/09/02 03:19:19 DEBUG : file1: reading active writers
2026/09/02 03:19:19 DEBUG : file1: active writers 1
2026/09/02 03:19:19 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:20 DEBUG : Looking for writers
2026/09/02 03:19:20 DEBUG : file1: reading active writers
2026/09/02 03:19:20 DEBUG : file1: active writers 1
2026/09/02 03:19:20 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:21 DEBUG : Looking for writers
2026/09/02 03:19:21 DEBUG : file1: reading active writers
2026/09/02 03:19:21 DEBUG : file1: active writers 1
2026/09/02 03:19:21 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:22 DEBUG : Looking for writers
2026/09/02 03:19:22 DEBUG : file1: reading active writers
2026/09/02 03:19:22 DEBUG : file1: active writers 1
2026/09/02 03:19:22 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:23 DEBUG : Looking for writers
2026/09/02 03:19:23 DEBUG : file1: reading active writers
2026/09/02 03:19:23 DEBUG : file1: active writers 1
2026/09/02 03:19:23 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:24 DEBUG : Looking for writers
2026/09/02 03:19:24 DEBUG : file1: reading active writers
2026/09/02 03:19:24 DEBUG : file1: active writers 1
2026/09/02 03:19:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:25 DEBUG : Looking for writers
2026/09/02 03:19:25 DEBUG : file1: reading active writers
2026/09/02 03:19:25 DEBUG : file1: active writers 1
2026/09/02 03:19:25 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:26 DEBUG : Looking for writers
2026/09/02 03:19:26 DEBUG : file1: reading active writers
2026/09/02 03:19:26 DEBUG : file1: active writers 1
2026/09/02 03:19:26 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:27 DEBUG : Looking for writers
2026/09/02 03:19:27 DEBUG : file1: reading active writers
2026/09/02 03:19:27 DEBUG : file1: active writers 1
2026/09/02 03:19:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:28 DEBUG : Looking for writers
2026/09/02 03:19:28 DEBUG : file1: reading active writers
2026/09/02 03:19:28 DEBUG : file1: active writers 1
2026/09/02 03:19:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:29 DEBUG : Looking for writers
2026/09/02 03:19:29 DEBUG : file1: reading active writers
2026/09/02 03:19:29 DEBUG : file1: active writers 1
2026/09/02 03:19:29 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:30 DEBUG : Looking for writers
2026/09/02 03:19:30 DEBUG : file1: reading active writers
2026/09/02 03:19:30 DEBUG : file1: active writers 1
2026/09/02 03:19:30 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:31 DEBUG : Looking for writers
2026/09/02 03:19:31 DEBUG : file1: reading active writers
2026/09/02 03:19:31 DEBUG : file1: active writers 1
2026/09/02 03:19:31 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2026/09/02 03:19:32 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s
Cache: <nil>
2026/09/02 03:19:32 DEBUG : >WaitForWriters: 
--- SKIP: TestWriteFileModTimeWithOpenWriters (30.90s)
PASS
2026/09/02 03:19:32 DEBUG : premiumize.me root 'rclone-test-fojigox8wuno': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^TestWriteFileModTimeWithOpenWriters$'" - Finished OK in 31.225236127s (try 3/5)