"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirHandleMethods|TestDirRemove|TestDirRemoveName|TestVFSMkdir|TestVFSMkdirAll)$|^TestFileSetModTime$/^cache=off,open=true,write=false$'" - Starting (try 2/5) 2025/12/31 02:35:45 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xekidus4xaco" 2025/12/31 02:35:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/31 02:35:46 DEBUG : Starting OpenDrive session with ID: 3b53ff557aeac4a573b6b0bd85dafe07b75b1463e0e96272cf2aae14c5eb0f8f 2025/12/31 02:35:46 DEBUG : Creating backend with remote "/tmp/rclone3866413572" === RUN TestDirHandleMethods run.go:185: Remote "OpenDrive root 'rclone-test-xekidus4xaco'", Local "Local file system at /tmp/rclone3866413572", Modify Window "1s" 2025/12/31 02:35:46 INFO : OpenDrive root 'rclone-test-xekidus4xaco': poll-interval is not supported by this remote 2025/12/31 02:35:46 NOTICE: OpenDrive root 'rclone-test-xekidus4xaco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/31 02:35:48 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/31 02:35:49 DEBUG : WaitForWriters: timeout=30s 2025/12/31 02:35:49 DEBUG : dir: Looking for writers 2025/12/31 02:35:49 DEBUG : Looking for writers 2025/12/31 02:35:49 DEBUG : dir: reading active writers 2025/12/31 02:35:49 DEBUG : >WaitForWriters: run.go:130: removing dir "dir" failed - try 1/3: folder not empty 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: TestDirHandleMethods (13.08s) === RUN TestDirCreate run.go:185: Remote "OpenDrive root 'rclone-test-xekidus4xaco'", Local "Local file system at /tmp/rclone3866413572", Modify Window "1s" 2025/12/31 02:35:59 INFO : OpenDrive root 'rclone-test-xekidus4xaco': poll-interval is not supported by this remote 2025/12/31 02:35:59 NOTICE: OpenDrive root 'rclone-test-xekidus4xaco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/31 02:36:01 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/31 02:36:03 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/12/31 02:36:03 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/31 02:36:03 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/12/31 02:36:03 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/31 02:36:03 DEBUG : OpenDrive root 'rclone-test-xekidus4xaco': File to upload is small (5 bytes), uploading instead of streaming 2025/12/31 02:36:03 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2025/12/31 02:36:04 DEBUG : dir/potato: size = 5 OK 2025/12/31 02:36:04 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/12/31 02:36:04 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2025/12/31 02:36:04 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/31 02:36:04 DEBUG : WaitForWriters: timeout=30s 2025/12/31 02:36:04 DEBUG : dir: Looking for writers 2025/12/31 02:36:04 DEBUG : file1: reading active writers 2025/12/31 02:36:04 DEBUG : potato: reading active writers 2025/12/31 02:36:04 DEBUG : Looking for writers 2025/12/31 02:36:04 DEBUG : dir: reading active writers 2025/12/31 02:36:04 DEBUG : >WaitForWriters: --- PASS: TestDirCreate (6.46s) === RUN TestDirRemove run.go:185: Remote "OpenDrive root 'rclone-test-xekidus4xaco'", Local "Local file system at /tmp/rclone3866413572", Modify Window "1s" 2025/12/31 02:36:06 INFO : OpenDrive root 'rclone-test-xekidus4xaco': poll-interval is not supported by this remote 2025/12/31 02:36:06 NOTICE: OpenDrive root 'rclone-test-xekidus4xaco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/31 02:36:07 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/31 02:36:09 ERROR : dir/: Dir.Remove not empty 2025/12/31 02:36:09 DEBUG : dir/file1: Remove: 2025/12/31 02:36:09 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/12/31 02:36:09 DEBUG : dir/file1: >Remove: err= 2025/12/31 02:36:09 DEBUG : Added virtual directory entry vDel: "dir" 2025/12/31 02:36:10 DEBUG : WaitForWriters: timeout=30s 2025/12/31 02:36:10 DEBUG : Looking for writers 2025/12/31 02:36:10 DEBUG : >WaitForWriters: --- PASS: TestDirRemove (4.13s) === RUN TestDirRemoveName run.go:185: Remote "OpenDrive root 'rclone-test-xekidus4xaco'", Local "Local file system at /tmp/rclone3866413572", Modify Window "1s" 2025/12/31 02:36:10 INFO : OpenDrive root 'rclone-test-xekidus4xaco': poll-interval is not supported by this remote 2025/12/31 02:36:10 NOTICE: OpenDrive root 'rclone-test-xekidus4xaco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/31 02:36:11 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/31 02:36:14 DEBUG : dir/file1: Remove: 2025/12/31 02:36:14 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/12/31 02:36:14 DEBUG : dir/file1: >Remove: err= 2025/12/31 02:36:14 DEBUG : WaitForWriters: timeout=30s 2025/12/31 02:36:14 DEBUG : dir: Looking for writers 2025/12/31 02:36:14 DEBUG : Looking for writers 2025/12/31 02:36:14 DEBUG : dir: reading active writers 2025/12/31 02:36:14 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveName (5.34s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:185: Remote "OpenDrive root 'rclone-test-xekidus4xaco'", Local "Local file system at /tmp/rclone3866413572", Modify Window "1s" 2025/12/31 02:36:15 INFO : OpenDrive root 'rclone-test-xekidus4xaco': poll-interval is not supported by this remote 2025/12/31 02:36:15 NOTICE: OpenDrive root 'rclone-test-xekidus4xaco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/31 02:36:17 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/31 02:36:19 DEBUG : time_test: Uploading chunk 0, size=5, remain=0 2025/12/31 02:36:21 DEBUG : Can set mod time: true 2025/12/31 02:36:21 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/12/31 02:36:21 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/12/31 02:36:21 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/12/31 02:36:21 DEBUG : OpenDrive root 'rclone-test-xekidus4xaco': File to upload is small (0 bytes), uploading instead of streaming 2025/12/31 02:36:22 DEBUG : dir/file1: size = 0 OK 2025/12/31 02:36:22 DEBUG : dir/file1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/31 02:36:22 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2025/12/31 02:36:22 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/12/31 02:36:22 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/12/31 02:36:22 DEBUG : WaitForWriters: timeout=30s 2025/12/31 02:36:22 DEBUG : dir: Looking for writers 2025/12/31 02:36:22 DEBUG : file1: reading active writers 2025/12/31 02:36:22 DEBUG : Looking for writers 2025/12/31 02:36:22 DEBUG : dir: reading active writers 2025/12/31 02:36:22 DEBUG : >WaitForWriters: 2025/12/31 02:36:22 DEBUG : WaitForWriters: timeout=30s 2025/12/31 02:36:22 DEBUG : dir: Looking for writers 2025/12/31 02:36:22 DEBUG : file1: reading active writers 2025/12/31 02:36:22 DEBUG : Looking for writers 2025/12/31 02:36:22 DEBUG : dir: reading active writers 2025/12/31 02:36:22 DEBUG : >WaitForWriters: --- PASS: TestFileSetModTime (8.32s) --- PASS: TestFileSetModTime/cache=off,open=true,write=false (8.32s) === RUN TestVFSMkdir run.go:185: Remote "OpenDrive root 'rclone-test-xekidus4xaco'", Local "Local file system at /tmp/rclone3866413572", Modify Window "1s" 2025/12/31 02:36:24 INFO : OpenDrive root 'rclone-test-xekidus4xaco': poll-interval is not supported by this remote 2025/12/31 02:36:24 NOTICE: OpenDrive root 'rclone-test-xekidus4xaco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/31 02:36:24 DEBUG : Added virtual directory entry vAddDir: "/" 2025/12/31 02:36:25 DEBUG : Added virtual directory entry vAddDir: "a" 2025/12/31 02:36:26 DEBUG : Added virtual directory entry vAddDir: "b" 2025/12/31 02:36:27 DEBUG : Added virtual directory entry vAddDir: "c" 2025/12/31 02:36:28 DEBUG : Added virtual directory entry vAddDir: "d" 2025/12/31 02:36:28 DEBUG : WaitForWriters: timeout=30s 2025/12/31 02:36:28 DEBUG : a: Looking for writers 2025/12/31 02:36:28 DEBUG : b: Looking for writers 2025/12/31 02:36:28 DEBUG : c: Looking for writers 2025/12/31 02:36:28 DEBUG : d: Looking for writers 2025/12/31 02:36:28 DEBUG : Looking for writers 2025/12/31 02:36:28 DEBUG : Looking for writers 2025/12/31 02:36:28 DEBUG : /: reading active writers 2025/12/31 02:36:28 DEBUG : a: reading active writers 2025/12/31 02:36:28 DEBUG : b: reading active writers 2025/12/31 02:36:28 DEBUG : c: reading active writers 2025/12/31 02:36:28 DEBUG : d: reading active writers 2025/12/31 02:36:28 DEBUG : >WaitForWriters: --- PASS: TestVFSMkdir (6.78s) === RUN TestVFSMkdirAll run.go:185: Remote "OpenDrive root 'rclone-test-xekidus4xaco'", Local "Local file system at /tmp/rclone3866413572", Modify Window "1s" 2025/12/31 02:36:30 INFO : OpenDrive root 'rclone-test-xekidus4xaco': poll-interval is not supported by this remote 2025/12/31 02:36:30 NOTICE: OpenDrive root 'rclone-test-xekidus4xaco': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/31 02:36:31 DEBUG : Added virtual directory entry vAddDir: "a" 2025/12/31 02:36:32 DEBUG : a: Added virtual directory entry vAddDir: "b" 2025/12/31 02:36:33 DEBUG : a/b: Added virtual directory entry vAddDir: "c" 2025/12/31 02:36:33 DEBUG : a/b/c: Added virtual directory entry vAddDir: "d" 2025/12/31 02:36:36 DEBUG : WaitForWriters: timeout=30s 2025/12/31 02:36:36 DEBUG : a/b/c/d: Looking for writers 2025/12/31 02:36:36 DEBUG : a/b/c: Looking for writers 2025/12/31 02:36:36 DEBUG : d: reading active writers 2025/12/31 02:36:36 DEBUG : a/b: Looking for writers 2025/12/31 02:36:36 DEBUG : c: reading active writers 2025/12/31 02:36:36 DEBUG : a: Looking for writers 2025/12/31 02:36:36 DEBUG : b: reading active writers 2025/12/31 02:36:36 DEBUG : Looking for writers 2025/12/31 02:36:36 DEBUG : a: reading active writers 2025/12/31 02:36:36 DEBUG : >WaitForWriters: --- PASS: TestVFSMkdirAll (8.47s) PASS 2025/12/31 02:36:39 DEBUG : OpenDrive root 'rclone-test-xekidus4xaco': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirHandleMethods|TestDirRemove|TestDirRemoveName|TestVFSMkdir|TestVFSMkdirAll)$|^TestFileSetModTime$/^cache=off,open=true,write=false$'" - Finished OK in 53.968776016s (try 2/5)