"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestDirFileOpen|TestZipDirsInRoot)$'" - Starting (try 2/5) 2026/08/05 02:07:16 DEBUG : Creating backend with remote "TestPcloud:rclone-test-biqifus3cepi" 2026/08/05 02:07:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/05 02:07:17 DEBUG : Creating backend with remote "/tmp/rclone3046156522" === RUN TestDirFileOpen run.go:198: Remote "pcloud root 'rclone-test-biqifus3cepi'", Local "Local file system at /tmp/rclone3046156522", Modify Window "1s" 2026/08/05 02:07:17 NOTICE: pcloud root 'rclone-test-biqifus3cepi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/08/05 02:07:18 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir" (type: 0) 2026/08/05 02:07:18 DEBUG : changeNotify: relativePath="dir", type=0 2026/08/05 02:07:18 DEBUG : >changeNotify: 2026/08/05 02:07:18 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/08/05 02:07:18 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/08/05 02:07:18 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 2026/08/05 02:07:27 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2026/08/05 02:07:27 DEBUG : dir/sub/file0: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2026/08/05 02:07:27 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir/sub" (type: 0) 2026/08/05 02:07:27 DEBUG : changeNotify: relativePath="dir/sub", type=0 2026/08/05 02:07:27 DEBUG : dir: invalidating directory cache 2026/08/05 02:07:27 DEBUG : dir/sub: invalidating directory cache 2026/08/05 02:07:27 DEBUG : >changeNotify: 2026/08/05 02:07:27 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2026/08/05 02:07:27 DEBUG : dir/file1: Reset virtual modtime 2026/08/05 02:07:27 DEBUG : dir/sub/file0: >OpenFile: fd=, err=file does not exist dir_test.go:628: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:628 Error: Received unexpected error: file does not exist Test: TestDirFileOpen 2026/08/05 02:07:27 DEBUG : WaitForWriters: timeout=30s 2026/08/05 02:07:27 DEBUG : dir: Looking for writers 2026/08/05 02:07:27 DEBUG : file1: reading active writers 2026/08/05 02:07:27 DEBUG : Looking for writers 2026/08/05 02:07:27 DEBUG : dir: reading active writers 2026/08/05 02:07:27 DEBUG : >WaitForWriters: run.go:186: removing dir "dir" failed - try 1/3: sleeping 1s: rmdir failed: pcloud error: Folder is not empty. (2006) run.go:186: removing dir "dir" failed - try 2/3: sleeping 1.5s: rmdir failed: pcloud error: Folder is not empty. (2006) run.go:186: removing dir "dir" failed: rmdir failed: pcloud error: Folder is not empty. (2006) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:628 Error: Not equal: expected: []string{} actual : []string{"dir", "dir/sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "dir", + (string) (len=7) "dir/sub" } Test: TestDirFileOpen Messages: directories --- FAIL: TestDirFileOpen (20.62s) === RUN TestZipDirsInRoot run.go:198: Remote "pcloud root 'rclone-test-biqifus3cepi'", Local "Local file system at /tmp/rclone3046156522", Modify Window "1s" 2026/08/05 02:07:38 NOTICE: pcloud root 'rclone-test-biqifus3cepi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/08/05 02:07:38 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir1" (type: 0) 2026/08/05 02:07:38 DEBUG : changeNotify: relativePath="dir1", type=0 2026/08/05 02:07:38 DEBUG : >changeNotify: 2026/08/05 02:07:39 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir1/a.txt" (type: 1) 2026/08/05 02:07:39 DEBUG : changeNotify: relativePath="dir1/a.txt", type=1 2026/08/05 02:07:39 DEBUG : >changeNotify: 2026/08/05 02:07:39 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir2" (type: 0) 2026/08/05 02:07:39 DEBUG : changeNotify: relativePath="dir2", type=0 2026/08/05 02:07:39 DEBUG : >changeNotify: 2026/08/05 02:07:39 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir2/b.txt" (type: 1) 2026/08/05 02:07:39 DEBUG : changeNotify: relativePath="dir2/b.txt", type=1 2026/08/05 02:07:39 DEBUG : >changeNotify: 2026/08/05 02:07:39 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir3" (type: 0) 2026/08/05 02:07:39 DEBUG : changeNotify: relativePath="dir3", type=0 2026/08/05 02:07:39 DEBUG : >changeNotify: 2026/08/05 02:07:39 DEBUG : pcloud root 'rclone-test-biqifus3cepi': ChangeNotify: detected change in "dir3/c.txt" (type: 1) 2026/08/05 02:07:39 DEBUG : changeNotify: relativePath="dir3/c.txt", type=1 2026/08/05 02:07:39 DEBUG : >changeNotify: 2026/08/05 02:07:40 DEBUG : dir1/a.txt: Open: flags=O_RDONLY 2026/08/05 02:07:40 DEBUG : dir1/a.txt: >Open: fd=dir1/a.txt (r), err= 2026/08/05 02:07:40 DEBUG : dir1/a.txt: ChunkedReader.openRange at 0 length 134217728 2026/08/05 02:07:40 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Wed, 05 Aug 2026 02:07:40 +0000": parsing time "Wed, 05 Aug 2026 02:07:40 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 05 Aug 2026 02:07:40 +0000" as " " 2026/08/05 02:07:40 DEBUG : dir1/a.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2026/08/05 02:07:40 DEBUG : dir2/b.txt: Open: flags=O_RDONLY 2026/08/05 02:07:40 DEBUG : dir2/b.txt: >Open: fd=dir2/b.txt (r), err= 2026/08/05 02:07:40 DEBUG : dir2/b.txt: ChunkedReader.openRange at 0 length 134217728 2026/08/05 02:07:40 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Wed, 05 Aug 2026 02:07:40 +0000": parsing time "Wed, 05 Aug 2026 02:07:40 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 05 Aug 2026 02:07:40 +0000" as " " 2026/08/05 02:07:40 DEBUG : dir2/b.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2026/08/05 02:07:40 DEBUG : dir3/c.txt: Open: flags=O_RDONLY 2026/08/05 02:07:40 DEBUG : dir3/c.txt: >Open: fd=dir3/c.txt (r), err= 2026/08/05 02:07:40 DEBUG : dir3/c.txt: ChunkedReader.openRange at 0 length 134217728 2026/08/05 02:07:40 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Wed, 05 Aug 2026 02:07:40 +0000": parsing time "Wed, 05 Aug 2026 02:07:40 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 05 Aug 2026 02:07:40 +0000" as " " 2026/08/05 02:07:40 DEBUG : dir3/c.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2026/08/05 02:07:41 DEBUG : WaitForWriters: timeout=30s 2026/08/05 02:07:41 DEBUG : dir3: Looking for writers 2026/08/05 02:07:41 DEBUG : c.txt: reading active writers 2026/08/05 02:07:41 DEBUG : dir/sub: Looking for writers 2026/08/05 02:07:41 DEBUG : dir: Looking for writers 2026/08/05 02:07:41 DEBUG : sub: reading active writers 2026/08/05 02:07:41 DEBUG : dir1: Looking for writers 2026/08/05 02:07:41 DEBUG : a.txt: reading active writers 2026/08/05 02:07:41 DEBUG : dir2: Looking for writers 2026/08/05 02:07:41 DEBUG : b.txt: reading active writers 2026/08/05 02:07:41 DEBUG : Looking for writers 2026/08/05 02:07:41 DEBUG : dir3: reading active writers 2026/08/05 02:07:41 DEBUG : dir: reading active writers 2026/08/05 02:07:41 DEBUG : dir1: reading active writers 2026/08/05 02:07:41 DEBUG : dir2: reading active writers 2026/08/05 02:07:41 DEBUG : >WaitForWriters: --- PASS: TestZipDirsInRoot (3.78s) FAIL 2026/08/05 02:07:42 DEBUG : pcloud root 'rclone-test-biqifus3cepi': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestDirFileOpen|TestZipDirsInRoot)$'" - Finished ERROR in 25.947924995s (try 2/5): exit status 1: Failed [TestDirFileOpen]