"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^TestDirRename$|^TestFileSetModTime$/^cache=off,open=true,write=true$'" - Starting (try 2/5) 2026/09/07 05:31:28 DEBUG : Creating backend with remote "TestPcloud:rclone-test-birineb9yive" 2026/09/07 05:31:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/07 05:31:28 DEBUG : Creating backend with remote "/tmp/rclone1937620887" === RUN TestDirRename run.go:198: Remote "pcloud root 'rclone-test-birineb9yive'", Local "Local file system at /tmp/rclone1937620887", Modify Window "1s" 2026/09/07 05:31:28 NOTICE: pcloud root 'rclone-test-birineb9yive': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/09/07 05:31:29 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "rclone-test-birineb9yive" (type: 0) 2026/09/07 05:31:29 DEBUG : changeNotify: relativePath="rclone-test-birineb9yive", type=0 2026/09/07 05:31:29 DEBUG : >changeNotify: 2026/09/07 05:31:29 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir" (type: 0) 2026/09/07 05:31:29 DEBUG : changeNotify: relativePath="dir", type=0 2026/09/07 05:31:29 DEBUG : >changeNotify: 2026/09/07 05:31:29 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/09/07 05:31:29 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/09/07 05:31:29 DEBUG : >changeNotify: fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure 2026/09/07 05:31:37 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir/file3" (type: 1) 2026/09/07 05:31:37 DEBUG : changeNotify: relativePath="dir/file3", type=1 2026/09/07 05:31:37 DEBUG : >changeNotify: 2026/09/07 05:31:38 ERROR : dir/not found: Dir.Rename error: file does not exist 2026/09/07 05:31:38 DEBUG : dir: Updating dir with dir2 0x19b81662c3c0 2026/09/07 05:31:38 DEBUG : dir: forgetting directory cache 2026/09/07 05:31:38 DEBUG : Added virtual directory entry vDel: "dir" 2026/09/07 05:31:38 DEBUG : Added virtual directory entry vAddDir: "dir2" 2026/09/07 05:31:38 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir2" (type: 0) 2026/09/07 05:31:38 DEBUG : changeNotify: relativePath="dir2", type=0 2026/09/07 05:31:38 DEBUG : invalidating directory cache 2026/09/07 05:31:38 DEBUG : dir2: invalidating directory cache 2026/09/07 05:31:38 DEBUG : >changeNotify: 2026/09/07 05:31:39 DEBUG : Removed virtual directory entry vDel: "dir" 2026/09/07 05:31:39 DEBUG : Removed virtual directory entry vAddDir: "dir2" 2026/09/07 05:31:39 DEBUG : dir2/file1: Reset virtual modtime 2026/09/07 05:31:39 DEBUG : dir2/file3: Reset virtual modtime 2026/09/07 05:31:39 INFO : dir2/file1: Moved (server-side) to: file2 2026/09/07 05:31:39 DEBUG : file2: Updating file with file2 0x19b816240340 2026/09/07 05:31:39 DEBUG : dir2: Added virtual directory entry vDel: "file1" 2026/09/07 05:31:39 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/09/07 05:31:39 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "file2" (type: 1) 2026/09/07 05:31:39 DEBUG : changeNotify: relativePath="file2", type=1 2026/09/07 05:31:39 DEBUG : invalidating directory cache 2026/09/07 05:31:39 DEBUG : >changeNotify: 2026/09/07 05:31:39 DEBUG : Removed virtual directory entry vAddFile: "file2" 2026/09/07 05:31:39 DEBUG : file2: Reset virtual modtime 2026/09/07 05:31:39 INFO : dir2/file3: Deleted 2026/09/07 05:31:39 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir2/file3" (type: 1) 2026/09/07 05:31:39 DEBUG : changeNotify: relativePath="dir2/file3", type=1 2026/09/07 05:31:39 DEBUG : dir2: invalidating directory cache 2026/09/07 05:31:39 DEBUG : >changeNotify: 2026/09/07 05:31:40 INFO : file2: Moved (server-side) to: dir2/file3 2026/09/07 05:31:40 DEBUG : dir2/file3: Updating file with dir2/file3 0x19b816240340 2026/09/07 05:31:40 DEBUG : Added virtual directory entry vDel: "file2" 2026/09/07 05:31:40 DEBUG : dir2: Added virtual directory entry vAddFile: "file3" 2026/09/07 05:31:40 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir2/file3" (type: 1) 2026/09/07 05:31:40 DEBUG : changeNotify: relativePath="dir2/file3", type=1 2026/09/07 05:31:40 DEBUG : dir2: Removed virtual directory entry vDel: "file1" 2026/09/07 05:31:40 DEBUG : dir2: Removed virtual directory entry vAddFile: "file3" 2026/09/07 05:31:40 DEBUG : dir2: invalidating directory cache 2026/09/07 05:31:40 DEBUG : >changeNotify: dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:569 Error: Not equal: expected: []string{"file3,14,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=14) "file3,14,false" -} +([]string) Test: TestDirRename 2026/09/07 05:31:40 DEBUG : Added virtual directory entry vAddDir: "empty directory" 2026/09/07 05:31:40 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "empty directory" (type: 0) 2026/09/07 05:31:40 DEBUG : changeNotify: relativePath="empty directory", type=0 2026/09/07 05:31:40 DEBUG : invalidating directory cache 2026/09/07 05:31:40 DEBUG : >changeNotify: 2026/09/07 05:31:40 DEBUG : empty directory: Updating dir with renamed empty directory 0x19b81618e6c0 2026/09/07 05:31:40 DEBUG : empty directory: forgetting directory cache 2026/09/07 05:31:40 DEBUG : Added virtual directory entry vDel: "empty directory" 2026/09/07 05:31:40 DEBUG : Added virtual directory entry vAddDir: "renamed empty directory" 2026/09/07 05:31:40 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "renamed empty directory" (type: 0) 2026/09/07 05:31:40 DEBUG : changeNotify: relativePath="renamed empty directory", type=0 2026/09/07 05:31:40 DEBUG : Removed virtual directory entry vAddDir: "renamed empty directory" 2026/09/07 05:31:40 DEBUG : Removed virtual directory entry vDel: "file2" 2026/09/07 05:31:40 DEBUG : Removed virtual directory entry vDel: "empty directory" 2026/09/07 05:31:40 DEBUG : dir2: Renaming to "dir3" 2026/09/07 05:31:40 DEBUG : WaitForWriters: timeout=30s 2026/09/07 05:31:40 DEBUG : dir3: Looking for writers 2026/09/07 05:31:40 DEBUG : renamed empty directory: Looking for writers 2026/09/07 05:31:40 DEBUG : Looking for writers 2026/09/07 05:31:40 DEBUG : renamed empty directory: reading active writers 2026/09/07 05:31:40 DEBUG : dir3: reading active writers 2026/09/07 05:31:40 DEBUG : >WaitForWriters: 2026/09/07 05:31:40 DEBUG : invalidating directory cache 2026/09/07 05:31:40 DEBUG : >changeNotify: --- FAIL: TestDirRename (12.56s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:198: Remote "pcloud root 'rclone-test-birineb9yive'", Local "Local file system at /tmp/rclone1937620887", Modify Window "1s" 2026/09/07 05:31:41 NOTICE: pcloud root 'rclone-test-birineb9yive': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/09/07 05:31:41 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir" (type: 0) 2026/09/07 05:31:41 DEBUG : changeNotify: relativePath="dir", type=0 2026/09/07 05:31:41 DEBUG : >changeNotify: 2026/09/07 05:31:41 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/09/07 05:31:41 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/09/07 05:31:41 DEBUG : >changeNotify: 2026/09/07 05:31:42 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "time_test" (type: 1) 2026/09/07 05:31:42 DEBUG : changeNotify: relativePath="time_test", type=1 2026/09/07 05:31:42 DEBUG : invalidating directory cache 2026/09/07 05:31:42 DEBUG : >changeNotify: 2026/09/07 05:31:42 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "time_test" (type: 1) 2026/09/07 05:31:42 DEBUG : changeNotify: relativePath="time_test", type=1 2026/09/07 05:31:42 DEBUG : >changeNotify: 2026/09/07 05:31:42 DEBUG : Can set mod time: true 2026/09/07 05:31:42 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2026/09/07 05:31:42 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2026/09/07 05:31:42 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/09/07 05:31:42 DEBUG : pcloud root 'rclone-test-birineb9yive': File to upload is small (5 bytes), uploading instead of streaming 2026/09/07 05:31:42 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "time_test" (type: 1) 2026/09/07 05:31:42 DEBUG : changeNotify: relativePath="time_test", type=1 2026/09/07 05:31:42 DEBUG : >changeNotify: 2026/09/07 05:31:42 DEBUG : dir/file1: size = 5 OK 2026/09/07 05:31:42 DEBUG : dir/file1: sha1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2026/09/07 05:31:42 DEBUG : dir/file1: Size and sha1 of src and dst objects identical 2026/09/07 05:31:42 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/09/07 05:31:42 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/09/07 05:31:42 DEBUG : dir: invalidating directory cache 2026/09/07 05:31:42 DEBUG : >changeNotify: 2026/09/07 05:31:42 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2026/09/07 05:31:42 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/09/07 05:31:42 DEBUG : WaitForWriters: timeout=30s 2026/09/07 05:31:42 DEBUG : dir: Looking for writers 2026/09/07 05:31:42 DEBUG : file1: reading active writers 2026/09/07 05:31:42 DEBUG : Looking for writers 2026/09/07 05:31:42 DEBUG : dir: reading active writers 2026/09/07 05:31:42 DEBUG : >WaitForWriters: 2026/09/07 05:31:42 DEBUG : pcloud root 'rclone-test-birineb9yive': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/09/07 05:31:42 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/09/07 05:31:42 DEBUG : >changeNotify: 2026/09/07 05:31:42 DEBUG : WaitForWriters: timeout=30s 2026/09/07 05:31:42 DEBUG : dir: Looking for writers 2026/09/07 05:31:42 DEBUG : file1: reading active writers 2026/09/07 05:31:42 DEBUG : Looking for writers 2026/09/07 05:31:42 DEBUG : dir: reading active writers 2026/09/07 05:31:42 DEBUG : >WaitForWriters: --- PASS: TestFileSetModTime (1.75s) --- PASS: TestFileSetModTime/cache=off,open=true,write=true (1.75s) FAIL 2026/09/07 05:31:43 DEBUG : pcloud root 'rclone-test-birineb9yive': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^TestDirRename$|^TestFileSetModTime$/^cache=off,open=true,write=true$'" - Finished ERROR in 15.221355654s (try 2/5): exit status 1: Failed [TestDirRename]