"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^TestFileSetModTime$/^(cache=off,open=false,write=false|cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Starting (try 2/5)
2026/08/01 04:30:14 DEBUG : Creating backend with remote "TestPcloud:rclone-test-zejovow6biwe"
2026/08/01 04:30:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/01 04:30:15 DEBUG : Creating backend with remote "/tmp/rclone1165928317"
=== RUN   TestFileSetModTime
=== RUN   TestFileSetModTime/cache=off,open=false,write=false
    run.go:198: Remote "pcloud root 'rclone-test-zejovow6biwe'", Local "Local file system at /tmp/rclone1165928317", Modify Window "1s"
2026/08/01 04:30:15 NOTICE: pcloud root 'rclone-test-zejovow6biwe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/08/01 04:30:16 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir" (type: 0)
2026/08/01 04:30:16 DEBUG : changeNotify: relativePath="dir", type=0
2026/08/01 04:30:16 DEBUG : >changeNotify: 
2026/08/01 04:30:16 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/08/01 04:30:16 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/08/01 04:30:16 DEBUG : >changeNotify: 
2026/08/01 04:30:16 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "time_test" (type: 1)
2026/08/01 04:30:16 DEBUG : changeNotify: relativePath="time_test", type=1
2026/08/01 04:30:16 DEBUG : invalidating directory cache
2026/08/01 04:30:16 DEBUG : >changeNotify: 
2026/08/01 04:30:17 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "time_test" (type: 1)
2026/08/01 04:30:17 DEBUG : changeNotify: relativePath="time_test", type=1
2026/08/01 04:30:17 DEBUG : >changeNotify: 
2026/08/01 04:30:17 DEBUG : Can set mod time: true
2026/08/01 04:30:17 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "time_test" (type: 1)
2026/08/01 04:30:17 DEBUG : changeNotify: relativePath="time_test", type=1
2026/08/01 04:30:17 DEBUG : >changeNotify: 
2026/08/01 04:30:17 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK
2026/08/01 04:30:17 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/08/01 04:30:17 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/08/01 04:30:17 DEBUG : dir: invalidating directory cache
2026/08/01 04:30:17 DEBUG : >changeNotify: 
2026/08/01 04:30:17 DEBUG : WaitForWriters: timeout=30s
2026/08/01 04:30:17 DEBUG : dir: Looking for writers
2026/08/01 04:30:17 DEBUG : file1: reading active writers
2026/08/01 04:30:17 DEBUG : Looking for writers
2026/08/01 04:30:17 DEBUG : dir: reading active writers
2026/08/01 04:30:17 DEBUG : >WaitForWriters: 
=== RUN   TestFileSetModTime/cache=off,open=true,write=false
    run.go:198: Remote "pcloud root 'rclone-test-zejovow6biwe'", Local "Local file system at /tmp/rclone1165928317", Modify Window "1s"
2026/08/01 04:30:17 NOTICE: pcloud root 'rclone-test-zejovow6biwe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/08/01 04:30:17 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir" (type: 0)
2026/08/01 04:30:17 DEBUG : changeNotify: relativePath="dir", type=0
2026/08/01 04:30:17 DEBUG : >changeNotify: 
2026/08/01 04:30:17 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/08/01 04:30:17 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/08/01 04:30:17 DEBUG : >changeNotify: 
2026/08/01 04:30:18 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2026/08/01 04:30:18 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err=<nil>
2026/08/01 04:30:18 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2026/08/01 04:30:18 DEBUG : pcloud root 'rclone-test-zejovow6biwe': File to upload is small (0 bytes), uploading instead of streaming
2026/08/01 04:30:18 DEBUG : dir/file1: size = 0 OK
2026/08/01 04:30:18 DEBUG : dir/file1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/08/01 04:30:18 DEBUG : dir/file1: Size and sha1 of src and dst objects identical
2026/08/01 04:30:18 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/08/01 04:30:18 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/08/01 04:30:18 DEBUG : dir: invalidating directory cache
2026/08/01 04:30:18 DEBUG : >changeNotify: 
2026/08/01 04:30:18 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK
2026/08/01 04:30:18 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2026/08/01 04:30:18 DEBUG : WaitForWriters: timeout=30s
2026/08/01 04:30:18 DEBUG : dir: Looking for writers
2026/08/01 04:30:18 DEBUG : file1: reading active writers
2026/08/01 04:30:18 DEBUG : Looking for writers
2026/08/01 04:30:18 DEBUG : dir: reading active writers
2026/08/01 04:30:18 DEBUG : >WaitForWriters: 
2026/08/01 04:30:18 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/08/01 04:30:18 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/08/01 04:30:18 DEBUG : >changeNotify: 
2026/08/01 04:30:18 DEBUG : WaitForWriters: timeout=30s
2026/08/01 04:30:18 DEBUG : dir: Looking for writers
2026/08/01 04:30:18 DEBUG : file1: reading active writers
2026/08/01 04:30:18 DEBUG : Looking for writers
2026/08/01 04:30:18 DEBUG : dir: reading active writers
2026/08/01 04:30:18 DEBUG : >WaitForWriters: 
=== RUN   TestFileSetModTime/cache=off,open=true,write=true
    run.go:198: Remote "pcloud root 'rclone-test-zejovow6biwe'", Local "Local file system at /tmp/rclone1165928317", Modify Window "1s"
2026/08/01 04:30:18 NOTICE: pcloud root 'rclone-test-zejovow6biwe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/08/01 04:30:18 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir" (type: 0)
2026/08/01 04:30:18 DEBUG : changeNotify: relativePath="dir", type=0
2026/08/01 04:30:18 DEBUG : >changeNotify: 
2026/08/01 04:30:19 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/08/01 04:30:19 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/08/01 04:30:19 DEBUG : >changeNotify: 
2026/08/01 04:30:19 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2026/08/01 04:30:19 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err=<nil>
2026/08/01 04:30:19 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2026/08/01 04:30:19 DEBUG : pcloud root 'rclone-test-zejovow6biwe': File to upload is small (5 bytes), uploading instead of streaming
2026/08/01 04:30:19 DEBUG : dir/file1: size = 5 OK
2026/08/01 04:30:19 DEBUG : dir/file1: sha1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK
2026/08/01 04:30:19 DEBUG : dir/file1: Size and sha1 of src and dst objects identical
2026/08/01 04:30:19 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/08/01 04:30:19 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/08/01 04:30:19 DEBUG : dir: invalidating directory cache
2026/08/01 04:30:19 DEBUG : >changeNotify: 
2026/08/01 04:30:19 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK
2026/08/01 04:30:19 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2026/08/01 04:30:19 DEBUG : WaitForWriters: timeout=30s
2026/08/01 04:30:19 DEBUG : dir: Looking for writers
2026/08/01 04:30:19 DEBUG : file1: reading active writers
2026/08/01 04:30:19 DEBUG : Looking for writers
2026/08/01 04:30:19 DEBUG : dir: reading active writers
2026/08/01 04:30:19 DEBUG : >WaitForWriters: 
2026/08/01 04:30:19 DEBUG : pcloud root 'rclone-test-zejovow6biwe': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/08/01 04:30:19 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/08/01 04:30:19 DEBUG : >changeNotify: 
2026/08/01 04:30:19 DEBUG : WaitForWriters: timeout=30s
2026/08/01 04:30:19 DEBUG : dir: Looking for writers
2026/08/01 04:30:19 DEBUG : file1: reading active writers
2026/08/01 04:30:19 DEBUG : Looking for writers
2026/08/01 04:30:19 DEBUG : dir: reading active writers
2026/08/01 04:30:19 DEBUG : >WaitForWriters: 
--- PASS: TestFileSetModTime (4.80s)
    --- PASS: TestFileSetModTime/cache=off,open=false,write=false (2.06s)
    --- PASS: TestFileSetModTime/cache=off,open=true,write=false (1.25s)
    --- PASS: TestFileSetModTime/cache=off,open=true,write=true (1.49s)
PASS
2026/08/01 04:30:20 DEBUG : pcloud root 'rclone-test-zejovow6biwe': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^TestFileSetModTime$/^(cache=off,open=false,write=false|cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Finished OK in 5.885430572s (try 2/5)