"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirFileOpen|TestDirMetadataExtension|TestDirMkdirSub)$'" - Starting (try 3/5) 2025/12/15 02:04:55 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zojabax2wica" 2025/12/15 02:04:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/15 02:04:55 DEBUG : Starting OpenDrive session with ID: 09e8a3daea1d262aff9a96b516ccd3fe51c2865538705e66ca7777df573195c8 2025/12/15 02:04:55 DEBUG : Creating backend with remote "/tmp/rclone4102882574" === RUN TestDirCreate run.go:185: Remote "OpenDrive root 'rclone-test-zojabax2wica'", Local "Local file system at /tmp/rclone4102882574", Modify Window "1s" 2025/12/15 02:04:55 INFO : OpenDrive root 'rclone-test-zojabax2wica': poll-interval is not supported by this remote 2025/12/15 02:04:55 NOTICE: OpenDrive root 'rclone-test-zojabax2wica': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/15 02:04:57 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/15 02:04:59 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/12/15 02:04:59 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/15 02:04:59 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/12/15 02:04:59 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/15 02:04:59 DEBUG : OpenDrive root 'rclone-test-zojabax2wica': File to upload is small (5 bytes), uploading instead of streaming 2025/12/15 02:04:59 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2025/12/15 02:05:00 DEBUG : dir/potato: size = 5 OK 2025/12/15 02:05:00 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/12/15 02:05:00 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2025/12/15 02:05:00 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/15 02:05:00 DEBUG : WaitForWriters: timeout=30s 2025/12/15 02:05:00 DEBUG : dir: Looking for writers 2025/12/15 02:05:00 DEBUG : file1: reading active writers 2025/12/15 02:05:00 DEBUG : potato: reading active writers 2025/12/15 02:05:00 DEBUG : Looking for writers 2025/12/15 02:05:00 DEBUG : dir: reading active writers 2025/12/15 02:05:00 DEBUG : >WaitForWriters: --- PASS: TestDirCreate (6.05s) === RUN TestDirMkdirSub run.go:185: Remote "OpenDrive root 'rclone-test-zojabax2wica'", Local "Local file system at /tmp/rclone4102882574", Modify Window "1s" 2025/12/15 02:05:01 INFO : OpenDrive root 'rclone-test-zojabax2wica': poll-interval is not supported by this remote 2025/12/15 02:05:01 NOTICE: OpenDrive root 'rclone-test-zojabax2wica': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/15 02:05:03 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/15 02:05:05 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/12/15 02:05:05 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub" 2025/12/15 02:05:05 DEBUG : dir/sub/subsub: Dir.ReadDirAll error: failed to get folder list: Directory doesn't exist (Error 404) dir_test.go:246: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:246 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:427 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestDirMkdirSub 2025/12/15 02:05:05 DEBUG : WaitForWriters: timeout=30s 2025/12/15 02:05:05 DEBUG : dir/sub/subsub: Looking for writers 2025/12/15 02:05:05 DEBUG : dir/sub: Looking for writers 2025/12/15 02:05:05 DEBUG : subsub: reading active writers 2025/12/15 02:05:05 DEBUG : dir: Looking for writers 2025/12/15 02:05:05 DEBUG : file1: reading active writers 2025/12/15 02:05:05 DEBUG : sub: reading active writers 2025/12/15 02:05:05 DEBUG : Looking for writers 2025/12/15 02:05:05 DEBUG : dir: reading active writers 2025/12/15 02:05:05 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdirSub (6.11s) === RUN TestDirFileOpen run.go:185: Remote "OpenDrive root 'rclone-test-zojabax2wica'", Local "Local file system at /tmp/rclone4102882574", Modify Window "1s" 2025/12/15 02:05:08 INFO : OpenDrive root 'rclone-test-zojabax2wica': poll-interval is not supported by this remote 2025/12/15 02:05:08 NOTICE: OpenDrive root 'rclone-test-zojabax2wica': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/15 02:05:09 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/15 02:05:11 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/12/15 02:05:11 DEBUG : dir/sub/file0: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2025/12/15 02:05:11 DEBUG : dir/sub/file0: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2025/12/15 02:05:11 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/12/15 02:05:11 DEBUG : dir/sub/file0: >Open: fd=dir/sub/file0 (w), err= 2025/12/15 02:05:11 DEBUG : dir/sub/file0: >OpenFile: fd=dir/sub/file0 (w), err= 2025/12/15 02:05:11 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/12/15 02:05:11 DEBUG : dir/sub/file2: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2025/12/15 02:05:11 DEBUG : dir/sub/file2: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2025/12/15 02:05:11 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/12/15 02:05:11 DEBUG : dir/sub/file2: >Open: fd=dir/sub/file2 (w), err= 2025/12/15 02:05:11 DEBUG : dir/sub/file2: >OpenFile: fd=dir/sub/file2 (w), err= 2025/12/15 02:05:11 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/12/15 02:05:11 DEBUG : OpenDrive root 'rclone-test-zojabax2wica': File to upload is small (12 bytes), uploading instead of streaming 2025/12/15 02:05:12 DEBUG : dir/sub/file2: Uploading chunk 0, size=12, remain=0 2025/12/15 02:05:13 DEBUG : dir/sub/file2: size = 12 OK 2025/12/15 02:05:13 DEBUG : dir/sub/file2: md5 = fc3ff98e8c6a0d3087d515c0473f8677 OK 2025/12/15 02:05:13 DEBUG : dir/sub/file2: Size and md5 of src and dst objects identical 2025/12/15 02:05:13 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/12/15 02:05:13 DEBUG : forgetting directory cache 2025/12/15 02:05:13 DEBUG : dir: forgetting directory cache 2025/12/15 02:05:13 DEBUG : dir/sub: forgetting directory cache 2025/12/15 02:05:13 DEBUG : dir/sub: Removed virtual directory entry vAddFile: "file2" 2025/12/15 02:05:13 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2025/12/15 02:05:13 DEBUG : OpenDrive root 'rclone-test-zojabax2wica': File to upload is small (5 bytes), uploading instead of streaming 2025/12/15 02:05:14 DEBUG : dir/sub/file0: Uploading chunk 0, size=5, remain=0 2025/12/15 02:05:15 DEBUG : dir/sub/file0: size = 5 OK 2025/12/15 02:05:15 DEBUG : dir/sub/file0: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/12/15 02:05:15 DEBUG : dir/sub/file0: Size and md5 of src and dst objects identical 2025/12/15 02:05:15 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/12/15 02:05:15 DEBUG : WaitForWriters: timeout=30s 2025/12/15 02:05:15 DEBUG : dir/sub: Looking for writers 2025/12/15 02:05:15 DEBUG : file0: reading active writers 2025/12/15 02:05:15 DEBUG : file2: reading active writers 2025/12/15 02:05:15 DEBUG : dir: Looking for writers 2025/12/15 02:05:15 DEBUG : sub: reading active writers 2025/12/15 02:05:15 DEBUG : file1: reading active writers 2025/12/15 02:05:15 DEBUG : Looking for writers 2025/12/15 02:05:15 DEBUG : dir: reading active writers 2025/12/15 02:05:15 DEBUG : >WaitForWriters: --- PASS: TestDirFileOpen (8.98s) === RUN TestDirMetadataExtension run.go:185: Remote "OpenDrive root 'rclone-test-zojabax2wica'", Local "Local file system at /tmp/rclone4102882574", Modify Window "1s" 2025/12/15 02:05:17 INFO : OpenDrive root 'rclone-test-zojabax2wica': poll-interval is not supported by this remote 2025/12/15 02:05:17 NOTICE: OpenDrive root 'rclone-test-zojabax2wica': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/15 02:05:18 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/15 02:05:19 DEBUG : dir: Added virtual directory entry vAddFile: "file1.metadata" 2025/12/15 02:05:19 DEBUG : dir/file1.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/12/15 02:05:19 DEBUG : dir/file1.metadata: Open: flags=O_RDONLY 2025/12/15 02:05:19 DEBUG : dir/file1.metadata: >Open: fd=dir/file1.metadata (r), err= 2025/12/15 02:05:19 DEBUG : dir/file1.metadata: >OpenFile: fd=dir/file1.metadata (r), err= 2025/12/15 02:05:19 DEBUG : dir/file1.metadata: ChunkedReader.openRange at 0 length 134217728 2025/12/15 02:05:19 DEBUG : dir/file1.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/12/15 02:05:19 DEBUG : Added virtual directory entry vAddFile: "dir.metadata" 2025/12/15 02:05:19 DEBUG : dir.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/12/15 02:05:19 DEBUG : dir.metadata: Open: flags=O_RDONLY 2025/12/15 02:05:19 DEBUG : dir.metadata: >Open: fd=dir.metadata (r), err= 2025/12/15 02:05:19 DEBUG : dir.metadata: >OpenFile: fd=dir.metadata (r), err= 2025/12/15 02:05:19 DEBUG : dir.metadata: ChunkedReader.openRange at 0 length 134217728 2025/12/15 02:05:19 DEBUG : dir.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/12/15 02:05:19 DEBUG : WaitForWriters: timeout=30s 2025/12/15 02:05:19 DEBUG : dir: Looking for writers 2025/12/15 02:05:19 DEBUG : file1: reading active writers 2025/12/15 02:05:19 DEBUG : file1.metadata: reading active writers 2025/12/15 02:05:19 DEBUG : Looking for writers 2025/12/15 02:05:19 DEBUG : dir.metadata: reading active writers 2025/12/15 02:05:19 DEBUG : dir: reading active writers 2025/12/15 02:05:19 DEBUG : >WaitForWriters: --- PASS: TestDirMetadataExtension (3.92s) FAIL 2025/12/15 02:05:20 DEBUG : OpenDrive root 'rclone-test-zojabax2wica': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirFileOpen|TestDirMetadataExtension|TestDirMkdirSub)$'" - Finished ERROR in 26.309333305s (try 3/5): exit status 1: Failed [TestDirMkdirSub]