"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^TestZipDirsInRoot$'" - Starting (try 4/5)
2026/02/27 04:10:39 DEBUG : Creating backend with remote "TestPcloud:rclone-test-vupamin1mane"
2026/02/27 04:10:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/27 04:10:40 DEBUG : Creating backend with remote "/tmp/rclone477944992"
=== RUN   TestZipDirsInRoot
    run.go:185: Remote "pcloud root 'rclone-test-vupamin1mane'", Local "Local file system at /tmp/rclone477944992", Modify Window "1s"
2026/02/27 04:10:40 NOTICE: pcloud root 'rclone-test-vupamin1mane': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/27 04:10:41 DEBUG : pcloud root 'rclone-test-vupamin1mane': ChangeNotify: detected change in "dir1" (type: 0)
2026/02/27 04:10:41 DEBUG : changeNotify: relativePath="dir1", type=0
2026/02/27 04:10:41 DEBUG : >changeNotify: 
2026/02/27 04:10:41 DEBUG : pcloud root 'rclone-test-vupamin1mane': ChangeNotify: detected change in "dir1/a.txt" (type: 1)
2026/02/27 04:10:41 DEBUG : changeNotify: relativePath="dir1/a.txt", type=1
2026/02/27 04:10:41 DEBUG : >changeNotify: 
2026/02/27 04:10:41 DEBUG : pcloud root 'rclone-test-vupamin1mane': ChangeNotify: detected change in "dir2" (type: 0)
2026/02/27 04:10:41 DEBUG : changeNotify: relativePath="dir2", type=0
2026/02/27 04:10:41 DEBUG : >changeNotify: 
2026/02/27 04:10:41 DEBUG : pcloud root 'rclone-test-vupamin1mane': ChangeNotify: detected change in "dir2/b.txt" (type: 1)
2026/02/27 04:10:41 DEBUG : changeNotify: relativePath="dir2/b.txt", type=1
2026/02/27 04:10:41 DEBUG : >changeNotify: 
2026/02/27 04:10:42 DEBUG : pcloud root 'rclone-test-vupamin1mane': ChangeNotify: detected change in "dir3" (type: 0)
2026/02/27 04:10:42 DEBUG : changeNotify: relativePath="dir3", type=0
2026/02/27 04:10:42 DEBUG : >changeNotify: 
2026/02/27 04:10:42 DEBUG : pcloud root 'rclone-test-vupamin1mane': ChangeNotify: detected change in "dir3/c.txt" (type: 1)
2026/02/27 04:10:42 DEBUG : changeNotify: relativePath="dir3/c.txt", type=1
2026/02/27 04:10:42 DEBUG : >changeNotify: 
2026/02/27 04:10:42 DEBUG : dir1/a.txt: Open: flags=O_RDONLY
2026/02/27 04:10:42 DEBUG : dir1/a.txt: >Open: fd=dir1/a.txt (r), err=<nil>
2026/02/27 04:10:42 DEBUG : dir1/a.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/27 04:10:42 DEBUG : Couldn't parse Date: from server edef5.pcloud.com: "Fri, 27 Feb 2026 04:10:42 +0000": parsing time "Fri, 27 Feb 2026 04:10:42 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Feb 2026 04:10:42 +0000" as " "
2026/02/27 04:10:42 DEBUG : dir1/a.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/27 04:10:43 DEBUG : dir2/b.txt: Open: flags=O_RDONLY
2026/02/27 04:10:43 DEBUG : dir2/b.txt: >Open: fd=dir2/b.txt (r), err=<nil>
2026/02/27 04:10:43 DEBUG : dir2/b.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/27 04:10:43 DEBUG : Couldn't parse Date: from server edef11.pcloud.com: "Fri, 27 Feb 2026 04:10:43 +0000": parsing time "Fri, 27 Feb 2026 04:10:43 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Feb 2026 04:10:43 +0000" as " "
2026/02/27 04:10:43 DEBUG : dir2/b.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/27 04:10:43 DEBUG : dir3/c.txt: Open: flags=O_RDONLY
2026/02/27 04:10:43 DEBUG : dir3/c.txt: >Open: fd=dir3/c.txt (r), err=<nil>
2026/02/27 04:10:43 DEBUG : dir3/c.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/27 04:10:43 DEBUG : Couldn't parse Date: from server edef2.pcloud.com: "Fri, 27 Feb 2026 04:10:43 +0000": parsing time "Fri, 27 Feb 2026 04:10:43 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Feb 2026 04:10:43 +0000" as " "
2026/02/27 04:10:43 DEBUG : dir3/c.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/27 04:10:43 DEBUG : WaitForWriters: timeout=30s
2026/02/27 04:10:43 DEBUG : dir1: Looking for writers
2026/02/27 04:10:43 DEBUG : a.txt: reading active writers
2026/02/27 04:10:43 DEBUG : dir2: Looking for writers
2026/02/27 04:10:43 DEBUG : b.txt: reading active writers
2026/02/27 04:10:43 DEBUG : dir3: Looking for writers
2026/02/27 04:10:43 DEBUG : c.txt: reading active writers
2026/02/27 04:10:43 DEBUG : Looking for writers
2026/02/27 04:10:43 DEBUG : dir1: reading active writers
2026/02/27 04:10:43 DEBUG : dir2: reading active writers
2026/02/27 04:10:43 DEBUG : dir3: reading active writers
2026/02/27 04:10:43 DEBUG : >WaitForWriters: 
2026/02/27 04:10:44 DEBUG : pcloud root 'rclone-test-vupamin1mane': ChangeNotify: detected change in "dir1/a.txt" (type: 1)
2026/02/27 04:10:44 DEBUG : changeNotify: relativePath="dir1/a.txt", type=1
2026/02/27 04:10:44 DEBUG : dir1: invalidating directory cache
2026/02/27 04:10:44 DEBUG : >changeNotify: 
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestZipDirsInRoot (11.84s)
PASS
2026/02/27 04:10:52 DEBUG : pcloud root 'rclone-test-vupamin1mane': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^TestZipDirsInRoot$'" - Finished OK in 12.777079215s (try 4/5)