"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirMkdir)$|^TestDirReadDirAll$/^Virtual$'" - Starting (try 2/5)
2024/11/12 03:25:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-buqewop0zuxo"
2024/11/12 03:25:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/12 03:25:21 DEBUG : Starting OpenDrive session with ID: fa46abe67d8363ce0980e4e6565dc4eeadbb5ea39d2b886a1b81757245635e19
2024/11/12 03:25:21 DEBUG : Creating backend with remote "/tmp/rclone1282067847"
=== RUN   TestDirReadDirAll
    run.go:180: Remote "OpenDrive root 'rclone-test-buqewop0zuxo'", Local "Local file system at /tmp/rclone1282067847", Modify Window "1s"
2024/11/12 03:25:21 INFO  : OpenDrive root 'rclone-test-buqewop0zuxo': poll-interval is not supported by this remote
2024/11/12 03:25:21 NOTICE: OpenDrive root 'rclone-test-buqewop0zuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/11/12 03:25:23 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2024/11/12 03:25:24 DEBUG : dir/file2: Uploading chunk 0, size=15, remain=0
2024/11/12 03:25:26 DEBUG : dir/subdir/file3: Uploading chunk 0, size=16, remain=0
=== RUN   TestDirReadDirAll/Virtual
2024/11/12 03:25:28 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile"
2024/11/12 03:25:28 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir"
2024/11/12 03:25:28 DEBUG : dir: Added virtual directory entry vDel: "file2"
2024/11/12 03:25:28 DEBUG : dir: Added virtual directory entry vDel: "subdir"
2024/11/12 03:25:29 DEBUG : dir/virtualFile: Uploading chunk 0, size=20, remain=0
2024/11/12 03:25:31 DEBUG : dir/virtualDir/testFile: Uploading chunk 0, size=17, remain=0
2024/11/12 03:25:33 DEBUG : dir: invalidating directory cache
2024/11/12 03:25:33 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir"
2024/11/12 03:25:33 DEBUG : dir: Removed virtual directory entry vDel: "file2"
2024/11/12 03:25:33 DEBUG : dir: Removed virtual directory entry vDel: "subdir"
2024/11/12 03:25:33 DEBUG : dir/file1: Reset virtual modtime
2024/11/12 03:25:33 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile"
2024/11/12 03:25:33 DEBUG : dir/virtualFile: Reset virtual modtime
2024/11/12 03:25:33 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2"
2024/11/12 03:25:33 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2"
2024/11/12 03:25:33 DEBUG : dir: Added virtual directory entry vDel: "file1"
2024/11/12 03:25:33 DEBUG : dir: invalidating directory cache
2024/11/12 03:25:33 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2"
2024/11/12 03:25:33 DEBUG : dir: Removed virtual directory entry vDel: "file1"
2024/11/12 03:25:33 DEBUG : dir/virtualFile: Reset virtual modtime
2024/11/12 03:25:33 DEBUG : : forgetting directory cache
2024/11/12 03:25:33 DEBUG : dir: forgetting directory cache
2024/11/12 03:25:33 DEBUG : dir/virtualDir: forgetting directory cache
2024/11/12 03:25:33 DEBUG : dir/file1: Reset virtual modtime
2024/11/12 03:25:33 DEBUG : dir/virtualFile: Reset virtual modtime
2024/11/12 03:25:33 DEBUG : WaitForWriters: timeout=30s
2024/11/12 03:25:33 DEBUG : dir/virtualDir: Looking for writers
2024/11/12 03:25:33 DEBUG : dir: Looking for writers
2024/11/12 03:25:33 DEBUG : virtualFile: reading active writers
2024/11/12 03:25:33 DEBUG : virtualDir: reading active writers
2024/11/12 03:25:33 DEBUG : file1: reading active writers
2024/11/12 03:25:33 DEBUG : virtualFile2: reading active writers
2024/11/12 03:25:33 DEBUG : : Looking for writers
2024/11/12 03:25:33 DEBUG : dir: reading active writers
2024/11/12 03:25:33 DEBUG : >WaitForWriters: 
--- PASS: TestDirReadDirAll (14.54s)
    --- PASS: TestDirReadDirAll/Virtual (4.99s)
=== RUN   TestDirCreate
    run.go:180: Remote "OpenDrive root 'rclone-test-buqewop0zuxo'", Local "Local file system at /tmp/rclone1282067847", Modify Window "1s"
2024/11/12 03:25:35 INFO  : OpenDrive root 'rclone-test-buqewop0zuxo': poll-interval is not supported by this remote
2024/11/12 03:25:35 NOTICE: OpenDrive root 'rclone-test-buqewop0zuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/11/12 03:25:37 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
    dir_test.go:351: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:351
        	Error:      	Should be true
        	Test:       	TestDirCreate
2024/11/12 03:25:38 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE
2024/11/12 03:25:38 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2024/11/12 03:25:38 DEBUG : dir/potato: >Open: fd=dir/potato (w), err=<nil>
2024/11/12 03:25:38 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2024/11/12 03:25:38 DEBUG : OpenDrive root 'rclone-test-buqewop0zuxo': File to upload is small (5 bytes), uploading instead of streaming
2024/11/12 03:25:39 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0
2024/11/12 03:25:40 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2024/11/12 03:25:40 DEBUG : dir/potato: Size and md5 of src and dst objects identical
2024/11/12 03:25:40 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2024/11/12 03:25:40 DEBUG : WaitForWriters: timeout=30s
2024/11/12 03:25:40 DEBUG : dir: Looking for writers
2024/11/12 03:25:40 DEBUG : file1: reading active writers
2024/11/12 03:25:40 DEBUG : potato: reading active writers
2024/11/12 03:25:40 DEBUG : : Looking for writers
2024/11/12 03:25:40 DEBUG : dir: reading active writers
2024/11/12 03:25:40 DEBUG : >WaitForWriters: 
--- FAIL: TestDirCreate (6.03s)
=== RUN   TestDirMkdir
    run.go:180: Remote "OpenDrive root 'rclone-test-buqewop0zuxo'", Local "Local file system at /tmp/rclone1282067847", Modify Window "1s"
2024/11/12 03:25:42 INFO  : OpenDrive root 'rclone-test-buqewop0zuxo': poll-interval is not supported by this remote
2024/11/12 03:25:42 NOTICE: OpenDrive root 'rclone-test-buqewop0zuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/11/12 03:25:43 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2024/11/12 03:25:45 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2024/11/12 03:25:46 DEBUG : WaitForWriters: timeout=30s
2024/11/12 03:25:46 DEBUG : dir/sub: Looking for writers
2024/11/12 03:25:46 DEBUG : dir: Looking for writers
2024/11/12 03:25:46 DEBUG : file1: reading active writers
2024/11/12 03:25:46 DEBUG : sub: reading active writers
2024/11/12 03:25:46 DEBUG : : Looking for writers
2024/11/12 03:25:46 DEBUG : dir: reading active writers
2024/11/12 03:25:46 DEBUG : >WaitForWriters: 
--- PASS: TestDirMkdir (5.62s)
FAIL
2024/11/12 03:25:47 DEBUG : OpenDrive root 'rclone-test-buqewop0zuxo': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirMkdir)$|^TestDirReadDirAll$/^Virtual$'" - Finished ERROR in 27.68087386s (try 2/5): exit status 1: Failed [TestDirCreate]