"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirRemoveName|TestFileReadAtNonZeroLength)$'" - Starting (try 2/5)
2025/10/24 01:30:41 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yodadoj8fivu"
2025/10/24 01:30:41 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/24 01:30:42 DEBUG : Starting OpenDrive session with ID: a2115be25e58bae38aa4c214de988ceb05aa205e6c64d351649667ac004c64d0
2025/10/24 01:30:42 DEBUG : Creating backend with remote "/tmp/rclone4137561339"
=== RUN   TestDirCreate
    run.go:180: Remote "OpenDrive root 'rclone-test-yodadoj8fivu'", Local "Local file system at /tmp/rclone4137561339", Modify Window "1s"
2025/10/24 01:30:42 INFO  : OpenDrive root 'rclone-test-yodadoj8fivu': poll-interval is not supported by this remote
2025/10/24 01:30:42 NOTICE: OpenDrive root 'rclone-test-yodadoj8fivu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/10/24 01:30:44 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2025/10/24 01:30:46 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE
2025/10/24 01:30:46 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2025/10/24 01:30:46 DEBUG : dir/potato: >Open: fd=dir/potato (w), err=<nil>
2025/10/24 01:30:46 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2025/10/24 01:30:46 DEBUG : OpenDrive root 'rclone-test-yodadoj8fivu': File to upload is small (5 bytes), uploading instead of streaming
2025/10/24 01:30:47 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0
2025/10/24 01:30:47 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2025/10/24 01:30:47 DEBUG : dir/potato: Size and md5 of src and dst objects identical
2025/10/24 01:30:47 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2025/10/24 01:30:47 DEBUG : WaitForWriters: timeout=30s
2025/10/24 01:30:47 DEBUG : dir: Looking for writers
2025/10/24 01:30:47 DEBUG : file1: reading active writers
2025/10/24 01:30:47 DEBUG : potato: reading active writers
2025/10/24 01:30:47 DEBUG : Looking for writers
2025/10/24 01:30:47 DEBUG : dir: reading active writers
2025/10/24 01:30:47 DEBUG : >WaitForWriters: 
--- PASS: TestDirCreate (6.43s)
=== RUN   TestDirRemoveName
    run.go:180: Remote "OpenDrive root 'rclone-test-yodadoj8fivu'", Local "Local file system at /tmp/rclone4137561339", Modify Window "1s"
2025/10/24 01:30:49 INFO  : OpenDrive root 'rclone-test-yodadoj8fivu': poll-interval is not supported by this remote
2025/10/24 01:30:49 NOTICE: OpenDrive root 'rclone-test-yodadoj8fivu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/10/24 01:30:50 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2025/10/24 01:30:52 DEBUG : dir/file1: Remove: 
2025/10/24 01:30:52 DEBUG : dir: Added virtual directory entry vDel: "file1"
2025/10/24 01:30:52 DEBUG : dir/file1: >Remove: err=<nil>
2025/10/24 01:30:52 DEBUG : WaitForWriters: timeout=30s
2025/10/24 01:30:52 DEBUG : dir: Looking for writers
2025/10/24 01:30:52 DEBUG : Looking for writers
2025/10/24 01:30:52 DEBUG : dir: reading active writers
2025/10/24 01:30:52 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemoveName (4.47s)
=== RUN   TestFileReadAtNonZeroLength
    run.go:180: Remote "OpenDrive root 'rclone-test-yodadoj8fivu'", Local "Local file system at /tmp/rclone4137561339", Modify Window "1s"
2025/10/24 01:30:53 INFO  : OpenDrive root 'rclone-test-yodadoj8fivu': poll-interval is not supported by this remote
2025/10/24 01:30:53 NOTICE: OpenDrive root 'rclone-test-yodadoj8fivu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/10/24 01:30:53 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/10/24 01:30:53 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/10/24 01:30:53 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/10/24 01:30:53 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/10/24 01:30:53 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/10/24 01:30:53 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/10/24 01:30:53 DEBUG : OpenDrive root 'rclone-test-yodadoj8fivu': File to upload is small (100 bytes), uploading instead of streaming
2025/10/24 01:30:54 DEBUG : file1: Uploading chunk 0, size=100, remain=0
2025/10/24 01:30:55 DEBUG : file1: md5 = 05ab8eda4bc03f267978f9114d22c5cb OK
2025/10/24 01:30:55 DEBUG : file1: Size and md5 of src and dst objects identical
2025/10/24 01:30:55 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/10/24 01:30:55 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=----------
2025/10/24 01:30:55 DEBUG : file1: Open: flags=O_RDONLY
2025/10/24 01:30:55 DEBUG : file1: >Open: fd=file1 (r), err=<nil>
2025/10/24 01:30:55 DEBUG : file1: >OpenFile: fd=file1 (r), err=<nil>
2025/10/24 01:30:55 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728
2025/10/24 01:30:55 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728
2025/10/24 01:30:55 DEBUG : WaitForWriters: timeout=30s
2025/10/24 01:30:55 DEBUG : Looking for writers
2025/10/24 01:30:55 DEBUG : file1: reading active writers
2025/10/24 01:30:55 DEBUG : >WaitForWriters: 
--- PASS: TestFileReadAtNonZeroLength (2.89s)
PASS
2025/10/24 01:30:56 DEBUG : OpenDrive root 'rclone-test-yodadoj8fivu': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirRemoveName|TestFileReadAtNonZeroLength)$'" - Finished OK in 15.112596222s (try 2/5)