"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirRemove|TestDirRemoveName|TestDirRename|TestVFSMkdir)$|^TestFileRename$/^off,forceCache=false$'" - Starting (try 2/5) 2026/01/31 04:49:55 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cuxeqal9muzi" 2026/01/31 04:49:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/31 04:49:56 DEBUG : Starting OpenDrive session with ID: 7a96da0f8fff61d529aa11353418fb99056cdd0409961d55171799d3f48febda 2026/01/31 04:49:56 DEBUG : Creating backend with remote "/tmp/rclone1944179022" === RUN TestDirCreate run.go:185: Remote "OpenDrive root 'rclone-test-cuxeqal9muzi'", Local "Local file system at /tmp/rclone1944179022", Modify Window "1s" 2026/01/31 04:49:56 INFO : OpenDrive root 'rclone-test-cuxeqal9muzi': poll-interval is not supported by this remote 2026/01/31 04:49:56 NOTICE: OpenDrive root 'rclone-test-cuxeqal9muzi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/01/31 04:49:57 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 dir_test.go:354: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:354 Error: Should be true Test: TestDirCreate 2026/01/31 04:49:59 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2026/01/31 04:49:59 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/01/31 04:49:59 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2026/01/31 04:49:59 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/01/31 04:49:59 DEBUG : OpenDrive root 'rclone-test-cuxeqal9muzi': File to upload is small (5 bytes), uploading instead of streaming 2026/01/31 04:50:00 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2026/01/31 04:50:01 DEBUG : dir/potato: size = 5 OK 2026/01/31 04:50:01 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/01/31 04:50:01 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2026/01/31 04:50:01 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/01/31 04:50:01 DEBUG : WaitForWriters: timeout=30s 2026/01/31 04:50:01 DEBUG : dir: Looking for writers 2026/01/31 04:50:01 DEBUG : file1: reading active writers 2026/01/31 04:50:01 DEBUG : potato: reading active writers 2026/01/31 04:50:01 DEBUG : Looking for writers 2026/01/31 04:50:01 DEBUG : dir: reading active writers 2026/01/31 04:50:01 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (6.42s) === RUN TestDirRemove run.go:185: Remote "OpenDrive root 'rclone-test-cuxeqal9muzi'", Local "Local file system at /tmp/rclone1944179022", Modify Window "1s" 2026/01/31 04:50:02 INFO : OpenDrive root 'rclone-test-cuxeqal9muzi': poll-interval is not supported by this remote 2026/01/31 04:50:02 NOTICE: OpenDrive root 'rclone-test-cuxeqal9muzi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/01/31 04:50:03 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 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/01/31 04:50:13 ERROR : dir/: Dir.Remove not empty 2026/01/31 04:50:13 DEBUG : dir/file1: Remove: 2026/01/31 04:50:13 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/01/31 04:50:13 DEBUG : dir/file1: >Remove: err= 2026/01/31 04:50:13 ERROR : dir/: Dir.Remove failed to remove directory: folder not empty dir_test.go:456: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:456 Error: Received unexpected error: folder not empty Test: TestDirRemove 2026/01/31 04:50:13 DEBUG : WaitForWriters: timeout=30s 2026/01/31 04:50:13 DEBUG : dir: Looking for writers 2026/01/31 04:50:13 DEBUG : Looking for writers 2026/01/31 04:50:13 DEBUG : dir: reading active writers 2026/01/31 04:50:13 DEBUG : >WaitForWriters: --- FAIL: TestDirRemove (11.53s) === RUN TestDirRemoveName run.go:185: Remote "OpenDrive root 'rclone-test-cuxeqal9muzi'", Local "Local file system at /tmp/rclone1944179022", Modify Window "1s" 2026/01/31 04:50:14 INFO : OpenDrive root 'rclone-test-cuxeqal9muzi': poll-interval is not supported by this remote 2026/01/31 04:50:14 NOTICE: OpenDrive root 'rclone-test-cuxeqal9muzi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/01/31 04:50:15 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2026/01/31 04:50:17 DEBUG : dir/file1: Remove: 2026/01/31 04:50:17 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/01/31 04:50:17 DEBUG : dir/file1: >Remove: err= 2026/01/31 04:50:17 DEBUG : WaitForWriters: timeout=30s 2026/01/31 04:50:17 DEBUG : dir: Looking for writers 2026/01/31 04:50:17 DEBUG : Looking for writers 2026/01/31 04:50:17 DEBUG : dir: reading active writers 2026/01/31 04:50:17 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveName (4.24s) === RUN TestDirRename run.go:185: Remote "OpenDrive root 'rclone-test-cuxeqal9muzi'", Local "Local file system at /tmp/rclone1944179022", Modify Window "1s" 2026/01/31 04:50:18 INFO : OpenDrive root 'rclone-test-cuxeqal9muzi': poll-interval is not supported by this remote 2026/01/31 04:50:18 NOTICE: OpenDrive root 'rclone-test-cuxeqal9muzi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/01/31 04:50:19 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2026/01/31 04:50:22 DEBUG : dir/file3: Uploading chunk 0, size=15, remain=0 2026/01/31 04:50:23 ERROR : dir/not found: Dir.Rename error: file does not exist 2026/01/31 04:50:23 DEBUG : dir2: same parent dir (ODFfOTcyNTM4Nl9ibEMxOA) - using folder/rename instead of move_copy 2026/01/31 04:50:23 DEBUG : dir: Updating dir with dir2 0xc00097cd00 2026/01/31 04:50:23 DEBUG : dir: forgetting directory cache 2026/01/31 04:50:23 DEBUG : Added virtual directory entry vDel: "dir" 2026/01/31 04:50:23 DEBUG : Added virtual directory entry vAddDir: "dir2" 2026/01/31 04:50:25 INFO : dir2/file1: Moved (server-side) to: file2 2026/01/31 04:50:25 DEBUG : file2: Updating file with file2 0xc0001af080 2026/01/31 04:50:25 DEBUG : dir2: Added virtual directory entry vDel: "file1" 2026/01/31 04:50:25 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/01/31 04:50:25 INFO : dir2/file3: Deleted 2026/01/31 04:50:26 INFO : file2: Moved (server-side) to: dir2/file3 2026/01/31 04:50:26 DEBUG : dir2/file3: Updating file with dir2/file3 0xc0001af080 2026/01/31 04:50:26 DEBUG : Added virtual directory entry vDel: "file2" 2026/01/31 04:50:26 DEBUG : dir2: Added virtual directory entry vAddFile: "file3" 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/01/31 04:50:34 DEBUG : Added virtual directory entry vAddDir: "empty directory" 2026/01/31 04:50:34 DEBUG : renamed empty directory: same parent dir (ODFfOTcyNTM4Nl9ibEMxOA) - using folder/rename instead of move_copy 2026/01/31 04:50:34 DEBUG : empty directory: Updating dir with renamed empty directory 0xc0006f0000 2026/01/31 04:50:34 DEBUG : empty directory: forgetting directory cache 2026/01/31 04:50:34 DEBUG : Added virtual directory entry vDel: "empty directory" 2026/01/31 04:50:34 DEBUG : Added virtual directory entry vAddDir: "renamed empty directory" 2026/01/31 04:50:34 DEBUG : dir2: Renaming to "dir3" 2026/01/31 04:50:34 DEBUG : WaitForWriters: timeout=30s 2026/01/31 04:50:34 DEBUG : dir3: Looking for writers 2026/01/31 04:50:34 DEBUG : file3: reading active writers 2026/01/31 04:50:34 DEBUG : renamed empty directory: Looking for writers 2026/01/31 04:50:34 DEBUG : Looking for writers 2026/01/31 04:50:34 DEBUG : dir3: reading active writers 2026/01/31 04:50:34 DEBUG : renamed empty directory: reading active writers 2026/01/31 04:50:34 DEBUG : >WaitForWriters: --- PASS: TestDirRename (18.14s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:185: Remote "OpenDrive root 'rclone-test-cuxeqal9muzi'", Local "Local file system at /tmp/rclone1944179022", Modify Window "1s" 2026/01/31 04:50:36 INFO : OpenDrive root 'rclone-test-cuxeqal9muzi': poll-interval is not supported by this remote 2026/01/31 04:50:36 NOTICE: OpenDrive root 'rclone-test-cuxeqal9muzi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/01/31 04:50:37 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 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/01/31 04:50:47 DEBUG : WaitForWriters: timeout=30s 2026/01/31 04:50:47 DEBUG : dir: Looking for writers 2026/01/31 04:50:47 DEBUG : file1: reading active writers 2026/01/31 04:50:47 DEBUG : Looking for writers 2026/01/31 04:50:47 DEBUG : dir: reading active writers 2026/01/31 04:50:47 DEBUG : >WaitForWriters: 2026/01/31 04:50:47 INFO : dir/file1: Moved (server-side) to: newLeaf 2026/01/31 04:50:47 DEBUG : newLeaf: Updating file with newLeaf 0xc0009e0000 2026/01/31 04:50:47 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/01/31 04:50:47 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2026/01/31 04:50:48 INFO : newLeaf: Moved (server-side) to: dir/file1 2026/01/31 04:50:48 DEBUG : dir/file1: Updating file with dir/file1 0xc0009e0000 2026/01/31 04:50:48 DEBUG : Added virtual directory entry vDel: "newLeaf" 2026/01/31 04:50:48 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/01/31 04:50:49 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2026/01/31 04:50:49 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2026/01/31 04:50:49 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/01/31 04:50:49 DEBUG : dir/file1: File is currently open, delaying rename 0xc0009e0000 2026/01/31 04:50:49 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/01/31 04:50:49 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2026/01/31 04:50:49 DEBUG : OpenDrive root 'rclone-test-cuxeqal9muzi': File to upload is small (25 bytes), uploading instead of streaming 2026/01/31 04:50:49 DEBUG : dir/file1: Uploading chunk 0, size=25, remain=0 2026/01/31 04:50:50 DEBUG : dir/file1: size = 25 OK 2026/01/31 04:50:50 DEBUG : dir/file1: md5 = c7247bead226dfd7bd970c17229f4044 OK 2026/01/31 04:50:50 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2026/01/31 04:50:50 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2026/01/31 04:50:50 DEBUG : newLeaf: Running delayed rename now 2026/01/31 04:50:51 INFO : dir/file1: Moved (server-side) to: newLeaf 2026/01/31 04:50:51 DEBUG : newLeaf: Updating file with newLeaf 0xc0009e0000 2026/01/31 04:50:51 DEBUG : WaitForWriters: timeout=30s 2026/01/31 04:50:51 DEBUG : dir: Looking for writers 2026/01/31 04:50:51 DEBUG : Looking for writers 2026/01/31 04:50:51 DEBUG : dir: reading active writers 2026/01/31 04:50:51 DEBUG : newLeaf: reading active writers 2026/01/31 04:50:51 DEBUG : >WaitForWriters: 2026/01/31 04:50:51 DEBUG : WaitForWriters: timeout=30s 2026/01/31 04:50:51 DEBUG : dir: Looking for writers 2026/01/31 04:50:51 DEBUG : Looking for writers 2026/01/31 04:50:51 DEBUG : dir: reading active writers 2026/01/31 04:50:51 DEBUG : newLeaf: reading active writers 2026/01/31 04:50:51 DEBUG : >WaitForWriters: 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: TestFileRename (23.25s) --- PASS: TestFileRename/off,forceCache=false (23.25s) === RUN TestVFSMkdir run.go:185: Remote "OpenDrive root 'rclone-test-cuxeqal9muzi'", Local "Local file system at /tmp/rclone1944179022", Modify Window "1s" 2026/01/31 04:50:59 INFO : OpenDrive root 'rclone-test-cuxeqal9muzi': poll-interval is not supported by this remote 2026/01/31 04:50:59 NOTICE: OpenDrive root 'rclone-test-cuxeqal9muzi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/01/31 04:51:00 DEBUG : Added virtual directory entry vAddDir: "/" 2026/01/31 04:51:00 DEBUG : Added virtual directory entry vAddDir: "a" 2026/01/31 04:51:01 DEBUG : Added virtual directory entry vAddDir: "b" 2026/01/31 04:51:02 DEBUG : Added virtual directory entry vAddDir: "c" 2026/01/31 04:51:03 DEBUG : Added virtual directory entry vAddDir: "d" 2026/01/31 04:51:04 DEBUG : WaitForWriters: timeout=30s 2026/01/31 04:51:04 DEBUG : Looking for writers 2026/01/31 04:51:04 DEBUG : a: Looking for writers 2026/01/31 04:51:04 DEBUG : b: Looking for writers 2026/01/31 04:51:04 DEBUG : c: Looking for writers 2026/01/31 04:51:04 DEBUG : d: Looking for writers 2026/01/31 04:51:04 DEBUG : Looking for writers 2026/01/31 04:51:04 DEBUG : d: reading active writers 2026/01/31 04:51:04 DEBUG : /: reading active writers 2026/01/31 04:51:04 DEBUG : a: reading active writers 2026/01/31 04:51:04 DEBUG : b: reading active writers 2026/01/31 04:51:04 DEBUG : c: reading active writers 2026/01/31 04:51:04 DEBUG : >WaitForWriters: --- PASS: TestVFSMkdir (5.95s) FAIL 2026/01/31 04:51:05 DEBUG : OpenDrive root 'rclone-test-cuxeqal9muzi': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirRemove|TestDirRemoveName|TestDirRename|TestVFSMkdir)$|^TestFileRename$/^off,forceCache=false$'" - Finished ERROR in 1m10.818088539s (try 2/5): exit status 1: Failed [TestDirCreate TestDirRemove]