"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirRemoveAll|TestDirRemoveName|TestFileReadAtNonZeroLength|TestUnicodeNormalization|TestVFSMkdir)$|^TestFileSetModTime$/^cache=off,open=false,write=false$'" - Starting (try 2/5) 2025/11/18 04:46:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-lusakul8peya" 2025/11/18 04:46:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 04:46:18 DEBUG : Starting OpenDrive session with ID: 2f358c435986856d473d097fcb81772f44d89959e2c7d50cc43aff0bbb36f48e 2025/11/18 04:46:19 DEBUG : Creating backend with remote "/tmp/rclone3026540390" === RUN TestDirCreate run.go:185: Remote "OpenDrive root 'rclone-test-lusakul8peya'", Local "Local file system at /tmp/rclone3026540390", Modify Window "1s" 2025/11/18 04:46:19 INFO : OpenDrive root 'rclone-test-lusakul8peya': poll-interval is not supported by this remote 2025/11/18 04:46:19 NOTICE: OpenDrive root 'rclone-test-lusakul8peya': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/18 04:46:20 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/11/18 04:46:22 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/11/18 04:46:22 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/11/18 04:46:22 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/11/18 04:46:22 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/11/18 04:46:22 DEBUG : OpenDrive root 'rclone-test-lusakul8peya': File to upload is small (5 bytes), uploading instead of streaming 2025/11/18 04:46:23 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2025/11/18 04:46:23 DEBUG : dir/potato: size = 5 OK 2025/11/18 04:46:23 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/11/18 04:46:23 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2025/11/18 04:46:23 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/11/18 04:46:23 DEBUG : WaitForWriters: timeout=30s 2025/11/18 04:46:23 DEBUG : dir: Looking for writers 2025/11/18 04:46:23 DEBUG : file1: reading active writers 2025/11/18 04:46:23 DEBUG : potato: reading active writers 2025/11/18 04:46:23 DEBUG : Looking for writers 2025/11/18 04:46:23 DEBUG : dir: reading active writers 2025/11/18 04:46:23 DEBUG : >WaitForWriters: --- PASS: TestDirCreate (6.15s) === RUN TestDirRemoveAll run.go:185: Remote "OpenDrive root 'rclone-test-lusakul8peya'", Local "Local file system at /tmp/rclone3026540390", Modify Window "1s" 2025/11/18 04:46:25 INFO : OpenDrive root 'rclone-test-lusakul8peya': poll-interval is not supported by this remote 2025/11/18 04:46:25 NOTICE: OpenDrive root 'rclone-test-lusakul8peya': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/18 04:46:26 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/11/18 04:46:27 DEBUG : dir/file1: Remove: 2025/11/18 04:46:28 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/11/18 04:46:28 DEBUG : dir/file1: >Remove: err= 2025/11/18 04:46:28 DEBUG : Added virtual directory entry vDel: "dir" 2025/11/18 04:46:28 DEBUG : WaitForWriters: timeout=30s 2025/11/18 04:46:28 DEBUG : Looking for writers 2025/11/18 04:46:28 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveAll (3.82s) === RUN TestDirRemoveName run.go:185: Remote "OpenDrive root 'rclone-test-lusakul8peya'", Local "Local file system at /tmp/rclone3026540390", Modify Window "1s" 2025/11/18 04:46:28 INFO : OpenDrive root 'rclone-test-lusakul8peya': poll-interval is not supported by this remote 2025/11/18 04:46:28 NOTICE: OpenDrive root 'rclone-test-lusakul8peya': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/18 04:46:30 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/11/18 04:46:31 DEBUG : dir/file1: Remove: 2025/11/18 04:46:32 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/11/18 04:46:32 DEBUG : dir/file1: >Remove: err= dir_test.go:504: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:504 Error: Should be true Test: TestDirRemoveName 2025/11/18 04:46:32 DEBUG : WaitForWriters: timeout=30s 2025/11/18 04:46:32 DEBUG : dir: Looking for writers 2025/11/18 04:46:32 DEBUG : Looking for writers 2025/11/18 04:46:32 DEBUG : dir: reading active writers 2025/11/18 04:46:32 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (4.29s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:185: Remote "OpenDrive root 'rclone-test-lusakul8peya'", Local "Local file system at /tmp/rclone3026540390", Modify Window "1s" 2025/11/18 04:46:33 INFO : OpenDrive root 'rclone-test-lusakul8peya': poll-interval is not supported by this remote 2025/11/18 04:46:33 NOTICE: OpenDrive root 'rclone-test-lusakul8peya': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/18 04:46:34 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/11/18 04:46:36 DEBUG : time_test: Uploading chunk 0, size=5, remain=0 2025/11/18 04:46:38 DEBUG : Can set mod time: true 2025/11/18 04:46:38 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/11/18 04:46:38 DEBUG : WaitForWriters: timeout=30s 2025/11/18 04:46:38 DEBUG : dir: Looking for writers 2025/11/18 04:46:38 DEBUG : file1: reading active writers 2025/11/18 04:46:38 DEBUG : Looking for writers 2025/11/18 04:46:38 DEBUG : dir: reading active writers 2025/11/18 04:46:38 DEBUG : >WaitForWriters: --- PASS: TestFileSetModTime (6.57s) --- PASS: TestFileSetModTime/cache=off,open=false,write=false (6.57s) === RUN TestUnicodeNormalization run.go:185: Remote "OpenDrive root 'rclone-test-lusakul8peya'", Local "Local file system at /tmp/rclone3026540390", Modify Window "1s" 2025/11/18 04:46:40 DEBUG : normal name with no special characters.txt: Uploading chunk 0, size=5, remain=0 2025/11/18 04:46:42 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 0, size=5, remain=0 2025/11/18 04:46:43 INFO : OpenDrive root 'rclone-test-lusakul8peya': poll-interval is not supported by this remote 2025/11/18 04:46:43 NOTICE: OpenDrive root 'rclone-test-lusakul8peya': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/18 04:46:43 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/11/18 04:46:43 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY 2025/11/18 04:46:43 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err= 2025/11/18 04:46:43 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err= 2025/11/18 04:46:43 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728 2025/11/18 04:46:43 DEBUG : normal name with no special characters.txt: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2025/11/18 04:46:43 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/11/18 04:46:43 DEBUG : 測試_Русский___ě_áñ: Open: flags=O_RDONLY 2025/11/18 04:46:43 DEBUG : 測試_Русский___ě_áñ: >Open: fd=測試_Русский___ě_áñ (r), err= 2025/11/18 04:46:43 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=測試_Русский___ě_áñ (r), err= 2025/11/18 04:46:43 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.openRange at 0 length 134217728 2025/11/18 04:46:44 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2025/11/18 04:46:44 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/11/18 04:46:44 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY 2025/11/18 04:46:44 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err= 2025/11/18 04:46:44 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err= 2025/11/18 04:46:44 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728 2025/11/18 04:46:44 DEBUG : normal name with no special characters.txt: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2025/11/18 04:46:44 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2025/11/18 04:46:44 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=, err=file does not exist 2025/11/18 04:46:44 DEBUG : WaitForWriters: timeout=30s 2025/11/18 04:46:44 DEBUG : Looking for writers 2025/11/18 04:46:44 DEBUG : 測試_Русский___ě_áñ: reading active writers 2025/11/18 04:46:44 DEBUG : normal name with no special characters.txt: reading active writers 2025/11/18 04:46:44 DEBUG : >WaitForWriters: --- PASS: TestUnicodeNormalization (5.65s) === RUN TestVFSMkdir run.go:185: Remote "OpenDrive root 'rclone-test-lusakul8peya'", Local "Local file system at /tmp/rclone3026540390", Modify Window "1s" 2025/11/18 04:46:45 INFO : OpenDrive root 'rclone-test-lusakul8peya': poll-interval is not supported by this remote 2025/11/18 04:46:45 NOTICE: OpenDrive root 'rclone-test-lusakul8peya': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/18 04:46:45 DEBUG : Added virtual directory entry vAddDir: "/" 2025/11/18 04:46:46 DEBUG : Added virtual directory entry vAddDir: "a" 2025/11/18 04:46:47 DEBUG : Added virtual directory entry vAddDir: "b" 2025/11/18 04:46:48 DEBUG : Added virtual directory entry vAddDir: "c" 2025/11/18 04:46:49 DEBUG : Added virtual directory entry vAddDir: "d" 2025/11/18 04:46:49 DEBUG : WaitForWriters: timeout=30s 2025/11/18 04:46:49 DEBUG : c: Looking for writers 2025/11/18 04:46:49 DEBUG : d: Looking for writers 2025/11/18 04:46:49 DEBUG : Looking for writers 2025/11/18 04:46:49 DEBUG : a: Looking for writers 2025/11/18 04:46:49 DEBUG : b: Looking for writers 2025/11/18 04:46:49 DEBUG : Looking for writers 2025/11/18 04:46:49 DEBUG : d: reading active writers 2025/11/18 04:46:49 DEBUG : /: reading active writers 2025/11/18 04:46:49 DEBUG : a: reading active writers 2025/11/18 04:46:49 DEBUG : b: reading active writers 2025/11/18 04:46:49 DEBUG : c: reading active writers 2025/11/18 04:46:49 DEBUG : >WaitForWriters: --- PASS: TestVFSMkdir (6.32s) === RUN TestFileReadAtNonZeroLength run.go:185: Remote "OpenDrive root 'rclone-test-lusakul8peya'", Local "Local file system at /tmp/rclone3026540390", Modify Window "1s" 2025/11/18 04:46:51 INFO : OpenDrive root 'rclone-test-lusakul8peya': poll-interval is not supported by this remote 2025/11/18 04:46:51 NOTICE: OpenDrive root 'rclone-test-lusakul8peya': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/11/18 04:46:51 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/11/18 04:46:52 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/11/18 04:46:52 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/11/18 04:46:52 DEBUG : file1: >Open: fd=file1 (w), err= 2025/11/18 04:46:52 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/11/18 04:46:52 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/11/18 04:46:52 DEBUG : OpenDrive root 'rclone-test-lusakul8peya': File to upload is small (100 bytes), uploading instead of streaming 2025/11/18 04:46:52 DEBUG : file1: Uploading chunk 0, size=100, remain=0 2025/11/18 04:46:54 DEBUG : file1: size = 100 OK 2025/11/18 04:46:54 DEBUG : file1: md5 = aa080295671503e3c0565882562f8141 OK 2025/11/18 04:46:54 DEBUG : file1: Size and md5 of src and dst objects identical 2025/11/18 04:46:54 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/11/18 04:46:54 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2025/11/18 04:46:54 DEBUG : file1: Open: flags=O_RDONLY 2025/11/18 04:46:54 DEBUG : file1: >Open: fd=file1 (r), err= 2025/11/18 04:46:54 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2025/11/18 04:46:54 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2025/11/18 04:46:54 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2025/11/18 04:46:54 DEBUG : WaitForWriters: timeout=30s 2025/11/18 04:46:54 DEBUG : Looking for writers 2025/11/18 04:46:54 DEBUG : file1: reading active writers 2025/11/18 04:46:54 DEBUG : >WaitForWriters: --- PASS: TestFileReadAtNonZeroLength (3.20s) FAIL 2025/11/18 04:46:55 DEBUG : OpenDrive root 'rclone-test-lusakul8peya': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirRemoveAll|TestDirRemoveName|TestFileReadAtNonZeroLength|TestUnicodeNormalization|TestVFSMkdir)$|^TestFileSetModTime$/^cache=off,open=false,write=false$'" - Finished ERROR in 37.346708467s (try 2/5): exit status 1: Failed [TestDirRemoveName]