"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestDirCreate|TestDirMkdir|TestDirRemoveName|TestWriteFileHandleMethods)$'" - Starting (try 2/5)
2026/09/05 01:58:27 DEBUG : Creating backend with remote "TestPcloud:rclone-test-pexapuh8waco"
2026/09/05 01:58:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/05 01:58:28 DEBUG : Creating backend with remote "/tmp/rclone3021839373"
=== RUN   TestDirCreate
    run.go:198: Remote "pcloud root 'rclone-test-pexapuh8waco'", Local "Local file system at /tmp/rclone3021839373", Modify Window "1s"
2026/09/05 01:58:28 NOTICE: pcloud root 'rclone-test-pexapuh8waco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/09/05 01:58:29 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir" (type: 0)
2026/09/05 01:58:29 DEBUG : changeNotify: relativePath="dir", type=0
2026/09/05 01:58:29 DEBUG : >changeNotify: 
2026/09/05 01:58:29 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/09/05 01:58:29 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/09/05 01:58:29 DEBUG : >changeNotify: 
2026/09/05 01:58:29 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE
2026/09/05 01:58:29 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/09/05 01:58:29 DEBUG : dir/potato: >Open: fd=dir/potato (w), err=<nil>
2026/09/05 01:58:29 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/09/05 01:58:29 DEBUG : pcloud root 'rclone-test-pexapuh8waco': File to upload is small (5 bytes), uploading instead of streaming
2026/09/05 01:58:29 DEBUG : dir/potato: size = 5 OK
2026/09/05 01:58:29 DEBUG : dir/potato: sha1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK
2026/09/05 01:58:29 DEBUG : dir/potato: Size and sha1 of src and dst objects identical
2026/09/05 01:58:29 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/09/05 01:58:29 DEBUG : WaitForWriters: timeout=30s
2026/09/05 01:58:29 DEBUG : dir: Looking for writers
2026/09/05 01:58:29 DEBUG : file1: reading active writers
2026/09/05 01:58:29 DEBUG : potato: reading active writers
2026/09/05 01:58:29 DEBUG : Looking for writers
2026/09/05 01:58:29 DEBUG : dir: reading active writers
2026/09/05 01:58:29 DEBUG : >WaitForWriters: 
2026/09/05 01:58:29 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir/potato" (type: 1)
2026/09/05 01:58:29 DEBUG : changeNotify: relativePath="dir/potato", type=1
2026/09/05 01:58:29 DEBUG : dir: invalidating directory cache
2026/09/05 01:58:29 DEBUG : >changeNotify: 
--- PASS: TestDirCreate (1.80s)
=== RUN   TestDirMkdir
    run.go:198: Remote "pcloud root 'rclone-test-pexapuh8waco'", Local "Local file system at /tmp/rclone3021839373", Modify Window "1s"
2026/09/05 01:58:30 NOTICE: pcloud root 'rclone-test-pexapuh8waco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/09/05 01:58:30 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir" (type: 0)
2026/09/05 01:58:30 DEBUG : changeNotify: relativePath="dir", type=0
2026/09/05 01:58:30 DEBUG : >changeNotify: 
2026/09/05 01:58:30 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/09/05 01:58:30 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/09/05 01:58:30 DEBUG : >changeNotify: 
2026/09/05 01:58:31 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2026/09/05 01:58:31 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir/sub" (type: 0)
2026/09/05 01:58:31 DEBUG : changeNotify: relativePath="dir/sub", type=0
2026/09/05 01:58:31 DEBUG : dir: invalidating directory cache
2026/09/05 01:58:31 DEBUG : dir/sub: invalidating directory cache
2026/09/05 01:58:31 DEBUG : >changeNotify: 
2026/09/05 01:58:31 DEBUG : WaitForWriters: timeout=30s
2026/09/05 01:58:31 DEBUG : dir/sub: Looking for writers
2026/09/05 01:58:31 DEBUG : dir: Looking for writers
2026/09/05 01:58:31 DEBUG : file1: reading active writers
2026/09/05 01:58:31 DEBUG : sub: reading active writers
2026/09/05 01:58:31 DEBUG : Looking for writers
2026/09/05 01:58:31 DEBUG : dir: reading active writers
2026/09/05 01:58:31 DEBUG : >WaitForWriters: 
--- PASS: TestDirMkdir (1.74s)
=== RUN   TestDirRemoveName
    run.go:198: Remote "pcloud root 'rclone-test-pexapuh8waco'", Local "Local file system at /tmp/rclone3021839373", Modify Window "1s"
2026/09/05 01:58:32 NOTICE: pcloud root 'rclone-test-pexapuh8waco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/09/05 01:58:32 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir" (type: 0)
2026/09/05 01:58:32 DEBUG : changeNotify: relativePath="dir", type=0
2026/09/05 01:58:32 DEBUG : >changeNotify: 
2026/09/05 01:58:32 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/09/05 01:58:32 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/09/05 01:58:32 DEBUG : >changeNotify: 
2026/09/05 01:58:32 DEBUG : dir/file1: Remove: 
2026/09/05 01:58:32 DEBUG : dir: Added virtual directory entry vDel: "file1"
2026/09/05 01:58:32 DEBUG : dir/file1: >Remove: err=<nil>
2026/09/05 01:58:32 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/09/05 01:58:32 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/09/05 01:58:32 DEBUG : dir: invalidating directory cache
2026/09/05 01:58:32 DEBUG : >changeNotify: 
2026/09/05 01:58:33 DEBUG : WaitForWriters: timeout=30s
2026/09/05 01:58:33 DEBUG : dir: Looking for writers
2026/09/05 01:58:33 DEBUG : Looking for writers
2026/09/05 01:58:33 DEBUG : dir: reading active writers
2026/09/05 01:58:33 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemoveName (1.23s)
=== RUN   TestWriteFileHandleMethods
    run.go:198: Remote "pcloud root 'rclone-test-pexapuh8waco'", Local "Local file system at /tmp/rclone3021839373", Modify Window "1s"
2026/09/05 01:58:33 NOTICE: pcloud root 'rclone-test-pexapuh8waco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/09/05 01:58:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2026/09/05 01:58:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal
2026/09/05 01:58:33 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal
2026/09/05 01:58:33 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes
2026/09/05 01:58:33 DEBUG : pcloud root 'rclone-test-pexapuh8waco': File to upload is small (5 bytes), uploading instead of streaming
2026/09/05 01:58:33 DEBUG : file1: size = 5 OK
2026/09/05 01:58:33 DEBUG : file1: sha1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK
2026/09/05 01:58:33 DEBUG : file1: Size and sha1 of src and dst objects identical
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "file1" (type: 1)
2026/09/05 01:58:33 DEBUG : changeNotify: relativePath="file1", type=1
2026/09/05 01:58:33 DEBUG : invalidating directory cache
2026/09/05 01:58:33 DEBUG : >changeNotify: 
2026/09/05 01:58:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2026/09/05 01:58:33 DEBUG : Removed virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : file1: Reset virtual modtime
2026/09/05 01:58:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2026/09/05 01:58:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 ERROR : file1: WriteFileHandle: Can't open for write without O_TRUNC on existing file without --vfs-cache-mode >= writes
2026/09/05 01:58:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2026/09/05 01:58:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : pcloud root 'rclone-test-pexapuh8waco': File to upload is small (0 bytes), uploading instead of streaming
2026/09/05 01:58:33 DEBUG : file1: size = 0 OK
2026/09/05 01:58:33 DEBUG : file1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/09/05 01:58:33 DEBUG : file1: Size and sha1 of src and dst objects identical
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2026/09/05 01:58:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2026/09/05 01:58:33 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:33 DEBUG : pcloud root 'rclone-test-pexapuh8waco': File to upload is small (7 bytes), uploading instead of streaming
2026/09/05 01:58:33 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "file1" (type: 1)
2026/09/05 01:58:33 DEBUG : changeNotify: relativePath="file1", type=1
2026/09/05 01:58:33 DEBUG : invalidating directory cache
2026/09/05 01:58:33 DEBUG : >changeNotify: 
2026/09/05 01:58:34 DEBUG : file1: size = 7 OK
2026/09/05 01:58:34 DEBUG : file1: sha1 = 189d2b4d61d6c47f31a89ef5d008c201199ef899 OK
2026/09/05 01:58:34 DEBUG : file1: Size and sha1 of src and dst objects identical
2026/09/05 01:58:34 DEBUG : Added virtual directory entry vAddFile: "file1"
2026/09/05 01:58:34 DEBUG : pcloud root 'rclone-test-pexapuh8waco': ChangeNotify: detected change in "file1" (type: 1)
2026/09/05 01:58:34 DEBUG : changeNotify: relativePath="file1", type=1
2026/09/05 01:58:34 DEBUG : Removed virtual directory entry vAddFile: "file1"
2026/09/05 01:58:34 DEBUG : file1: Reset virtual modtime
2026/09/05 01:58:34 DEBUG : WaitForWriters: timeout=30s
2026/09/05 01:58:34 DEBUG : Looking for writers
2026/09/05 01:58:34 DEBUG : file1: reading active writers
2026/09/05 01:58:34 DEBUG : >WaitForWriters: 
2026/09/05 01:58:34 DEBUG : invalidating directory cache
2026/09/05 01:58:34 DEBUG : >changeNotify: 
--- PASS: TestWriteFileHandleMethods (1.07s)
PASS
2026/09/05 01:58:34 DEBUG : pcloud root 'rclone-test-pexapuh8waco': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestDirCreate|TestDirMkdir|TestDirRemoveName|TestWriteFileHandleMethods)$'" - Finished OK in 6.779564861s (try 2/5)