"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirMkdir|TestDirRemove|TestDirRemoveName|TestZipManySubDirs)$'" - Starting (try 2/5)
2026/02/01 02:31:46 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dirapaf7gawi"
2026/02/01 02:31:46 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/01 02:31:47 DEBUG : Starting OpenDrive session with ID: 847979616c262763656ed91f506b48915ad396bd3a7a090d89775291100b1a6e
2026/02/01 02:31:47 DEBUG : Creating backend with remote "/tmp/rclone757213584"
=== RUN   TestDirCreate
    run.go:185: Remote "OpenDrive root 'rclone-test-dirapaf7gawi'", Local "Local file system at /tmp/rclone757213584", Modify Window "1s"
2026/02/01 02:31:47 INFO  : OpenDrive root 'rclone-test-dirapaf7gawi': poll-interval is not supported by this remote
2026/02/01 02:31:47 NOTICE: OpenDrive root 'rclone-test-dirapaf7gawi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/01 02:31:49 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/02/01 02:31:50 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE
2026/02/01 02:31:50 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/02/01 02:31:50 DEBUG : dir/potato: >Open: fd=dir/potato (w), err=<nil>
2026/02/01 02:31:50 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/02/01 02:31:50 DEBUG : OpenDrive root 'rclone-test-dirapaf7gawi': File to upload is small (5 bytes), uploading instead of streaming
2026/02/01 02:31:51 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0
2026/02/01 02:31:52 DEBUG : dir/potato: size = 5 OK
2026/02/01 02:31:52 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/02/01 02:31:52 DEBUG : dir/potato: Size and md5 of src and dst objects identical
2026/02/01 02:31:52 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2026/02/01 02:31:52 DEBUG : WaitForWriters: timeout=30s
2026/02/01 02:31:52 DEBUG : dir: Looking for writers
2026/02/01 02:31:52 DEBUG : potato: reading active writers
2026/02/01 02:31:52 DEBUG : file1: reading active writers
2026/02/01 02:31:52 DEBUG : Looking for writers
2026/02/01 02:31:52 DEBUG : dir: reading active writers
2026/02/01 02:31:52 DEBUG : >WaitForWriters: 
    run.go:130: removing dir "dir" failed - try 1/3: folder not empty
--- FAIL: TestDirCreate (7.34s)
=== RUN   TestDirMkdir
    run.go:185: Remote "OpenDrive root 'rclone-test-dirapaf7gawi'", Local "Local file system at /tmp/rclone757213584", Modify Window "1s"
2026/02/01 02:31:54 INFO  : OpenDrive root 'rclone-test-dirapaf7gawi': poll-interval is not supported by this remote
2026/02/01 02:31:54 NOTICE: OpenDrive root 'rclone-test-dirapaf7gawi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/01 02:31:56 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2026/02/01 02:31:58 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2026/02/01 02:31:58 DEBUG : dir/sub: 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:402
        	Error:      	Received unexpected error:
        	            	failed to get folder list: Directory doesn't exist (Error 404)
        	Test:       	TestDirMkdir
2026/02/01 02:31:58 DEBUG : WaitForWriters: timeout=30s
2026/02/01 02:31:58 DEBUG : dir/sub: Looking for writers
2026/02/01 02:31:58 DEBUG : dir: Looking for writers
2026/02/01 02:31:58 DEBUG : file1: reading active writers
2026/02/01 02:31:58 DEBUG : sub: reading active writers
2026/02/01 02:31:58 DEBUG : Looking for writers
2026/02/01 02:31:58 DEBUG : dir: reading active writers
2026/02/01 02:31:58 DEBUG : >WaitForWriters: 
--- FAIL: TestDirMkdir (4.51s)
=== RUN   TestDirRemove
    run.go:185: Remote "OpenDrive root 'rclone-test-dirapaf7gawi'", Local "Local file system at /tmp/rclone757213584", Modify Window "1s"
2026/02/01 02:31:59 INFO  : OpenDrive root 'rclone-test-dirapaf7gawi': poll-interval is not supported by this remote
2026/02/01 02:31:59 NOTICE: OpenDrive root 'rclone-test-dirapaf7gawi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/01 02:32:00 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2026/02/01 02:32:02 ERROR : dir/: Dir.Remove not empty
2026/02/01 02:32:02 DEBUG : dir/file1: Remove: 
2026/02/01 02:32:02 DEBUG : dir: Added virtual directory entry vDel: "file1"
2026/02/01 02:32:02 DEBUG : dir/file1: >Remove: err=<nil>
2026/02/01 02:32:02 DEBUG : Added virtual directory entry vDel: "dir"
2026/02/01 02:32:02 DEBUG : WaitForWriters: timeout=30s
2026/02/01 02:32:02 DEBUG : Looking for writers
2026/02/01 02:32:02 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemove (3.70s)
=== RUN   TestDirRemoveName
    run.go:185: Remote "OpenDrive root 'rclone-test-dirapaf7gawi'", Local "Local file system at /tmp/rclone757213584", Modify Window "1s"
2026/02/01 02:32:03 INFO  : OpenDrive root 'rclone-test-dirapaf7gawi': poll-interval is not supported by this remote
2026/02/01 02:32:03 NOTICE: OpenDrive root 'rclone-test-dirapaf7gawi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/01 02:32:04 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/02/01 02:32:13 DEBUG : dir/file1: Remove: 
2026/02/01 02:32:13 DEBUG : dir: Added virtual directory entry vDel: "file1"
2026/02/01 02:32:13 DEBUG : dir/file1: >Remove: err=<nil>
2026/02/01 02:32:14 DEBUG : WaitForWriters: timeout=30s
2026/02/01 02:32:14 DEBUG : dir: Looking for writers
2026/02/01 02:32:14 DEBUG : Looking for writers
2026/02/01 02:32:14 DEBUG : dir: reading active writers
2026/02/01 02:32:14 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemoveName (11.69s)
=== RUN   TestZipManySubDirs
    run.go:185: Remote "OpenDrive root 'rclone-test-dirapaf7gawi'", Local "Local file system at /tmp/rclone757213584", Modify Window "1s"
2026/02/01 02:32:14 INFO  : OpenDrive root 'rclone-test-dirapaf7gawi': poll-interval is not supported by this remote
2026/02/01 02:32:14 NOTICE: OpenDrive root 'rclone-test-dirapaf7gawi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/02/01 02:32:15 DEBUG : a/top.txt: Uploading chunk 0, size=3, remain=0
2026/02/01 02:32:18 DEBUG : a/b/mid.txt: Uploading chunk 0, size=3, remain=0
2026/02/01 02:32:20 DEBUG : a/b/c/deep.txt: Uploading chunk 0, size=4, remain=0
2026/02/01 02:32:21 DEBUG : a/b/c/deep.txt: Open: flags=O_RDONLY
2026/02/01 02:32:21 DEBUG : a/b/c/deep.txt: >Open: fd=a/b/c/deep.txt (r), err=<nil>
2026/02/01 02:32:21 DEBUG : a/b/c/deep.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/01 02:32:22 DEBUG : a/b/c/deep.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/01 02:32:22 DEBUG : a/b/mid.txt: Open: flags=O_RDONLY
2026/02/01 02:32:22 DEBUG : a/b/mid.txt: >Open: fd=a/b/mid.txt (r), err=<nil>
2026/02/01 02:32:22 DEBUG : a/b/mid.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/01 02:32:22 DEBUG : a/b/mid.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/01 02:32:22 DEBUG : a/top.txt: Open: flags=O_RDONLY
2026/02/01 02:32:22 DEBUG : a/top.txt: >Open: fd=a/top.txt (r), err=<nil>
2026/02/01 02:32:22 DEBUG : a/top.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/01 02:32:22 DEBUG : a/top.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/01 02:32:22 DEBUG : WaitForWriters: timeout=30s
2026/02/01 02:32:22 DEBUG : a/b/c: Looking for writers
2026/02/01 02:32:22 DEBUG : deep.txt: reading active writers
2026/02/01 02:32:22 DEBUG : a/b: Looking for writers
2026/02/01 02:32:22 DEBUG : c: reading active writers
2026/02/01 02:32:22 DEBUG : mid.txt: reading active writers
2026/02/01 02:32:22 DEBUG : a: Looking for writers
2026/02/01 02:32:22 DEBUG : b: reading active writers
2026/02/01 02:32:22 DEBUG : top.txt: reading active writers
2026/02/01 02:32:22 DEBUG : Looking for writers
2026/02/01 02:32:22 DEBUG : a: reading active writers
2026/02/01 02:32:22 DEBUG : >WaitForWriters: 
--- PASS: TestZipManySubDirs (10.62s)
FAIL
2026/02/01 02:32:25 DEBUG : OpenDrive root 'rclone-test-dirapaf7gawi': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirMkdir|TestDirRemove|TestDirRemoveName|TestZipManySubDirs)$'" - Finished ERROR in 39.103902636s (try 2/5): exit status 1: Failed [TestDirCreate TestDirMkdir]