"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirRemoveName)$|^TestFileRename$/^off,forceCache=false$'" - Starting (try 5/5) 2025/09/30 05:59:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-pixovif9memi" 2025/09/30 05:59:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 05:59:22 DEBUG : Starting OpenDrive session with ID: 150c8386cffa33baa75a729bb298442ecf669c2f266947c67e5b4cbec183d073 2025/09/30 05:59:23 DEBUG : Creating backend with remote "/tmp/rclone3162860490" === RUN TestDirCreate run.go:180: Remote "OpenDrive root 'rclone-test-pixovif9memi'", Local "Local file system at /tmp/rclone3162860490", Modify Window "1s" 2025/09/30 05:59:23 INFO : OpenDrive root 'rclone-test-pixovif9memi': poll-interval is not supported by this remote 2025/09/30 05:59:23 NOTICE: OpenDrive root 'rclone-test-pixovif9memi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/30 05:59:24 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 2025/09/30 05:59:26 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/09/30 05:59:26 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/09/30 05:59:26 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/09/30 05:59:26 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/09/30 05:59:26 DEBUG : OpenDrive root 'rclone-test-pixovif9memi': File to upload is small (5 bytes), uploading instead of streaming 2025/09/30 05:59:27 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2025/09/30 05:59:27 DEBUG : dir/potato: Sizes differ (src 5 vs dst 0) 2025/09/30 05:59:27 ERROR : dir/potato: corrupted on transfer 2025/09/30 05:59:27 ERROR : dir/potato: WriteFileHandle.New Rcat failed: corrupted on transfer 2025/09/30 05:59:27 DEBUG : dir/potato: Remove: 2025/09/30 05:59:27 DEBUG : dir: Added virtual directory entry vDel: "potato" 2025/09/30 05:59:27 DEBUG : dir/potato: >Remove: err= dir_test.go:370: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:370 Error: Received unexpected error: corrupted on transfer Test: TestDirCreate 2025/09/30 05:59:27 DEBUG : WaitForWriters: timeout=30s 2025/09/30 05:59:27 DEBUG : dir: Looking for writers 2025/09/30 05:59:27 DEBUG : file1: reading active writers 2025/09/30 05:59:27 DEBUG : Looking for writers 2025/09/30 05:59:27 DEBUG : dir: reading active writers 2025/09/30 05:59:27 DEBUG : >WaitForWriters: run.go:125: removing dir "dir" failed - try 1/3: folder not empty run.go:125: removing dir "dir" failed - try 2/3: folder not empty run.go:125: removing dir "dir" failed - try 3/3: folder not empty run.go:128: removing dir "dir" failed: folder not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:370 Error: Should be true Test: TestDirCreate Messages: listing wrong, want got dir/potato (5) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:370 Error: Should be true Test: TestDirCreate Messages: Unexpected file "dir/potato" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:370 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestDirCreate Messages: directories --- FAIL: TestDirCreate (17.38s) === RUN TestDirRemoveName run.go:180: Remote "OpenDrive root 'rclone-test-pixovif9memi'", Local "Local file system at /tmp/rclone3162860490", Modify Window "1s" 2025/09/30 05:59:40 INFO : OpenDrive root 'rclone-test-pixovif9memi': poll-interval is not supported by this remote 2025/09/30 05:59:40 NOTICE: OpenDrive root 'rclone-test-pixovif9memi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/30 05:59:41 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:26 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:498 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestDirRemoveName Messages: listing wrong, want dir/file1 (14) got dir/file1 (14), dir/potato (5) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:26 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:498 Error: Should be true Test: TestDirRemoveName Messages: Unexpected file "dir/potato" 2025/09/30 05:59:51 DEBUG : dir/file1: Remove: 2025/09/30 05:59:51 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/09/30 05:59:51 DEBUG : dir/file1: >Remove: err= dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:505 Error: Not equal: expected: []string(nil) actual : []string{"potato,5,false"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) +([]string) (len=1) { + (string) (len=14) "potato,5,false" +} Test: TestDirRemoveName fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:511 Error: Should be true Test: TestDirRemoveName Messages: listing wrong, want got dir/potato (5) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:511 Error: Should be true Test: TestDirRemoveName Messages: Unexpected file "dir/potato" 2025/09/30 06:00:00 DEBUG : WaitForWriters: timeout=30s 2025/09/30 06:00:00 DEBUG : dir: Looking for writers 2025/09/30 06:00:00 DEBUG : potato: reading active writers 2025/09/30 06:00:00 DEBUG : Looking for writers 2025/09/30 06:00:00 DEBUG : dir: reading active writers 2025/09/30 06:00:00 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (20.97s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:180: Remote "OpenDrive root 'rclone-test-pixovif9memi'", Local "Local file system at /tmp/rclone3162860490", Modify Window "1s" 2025/09/30 06:00:01 INFO : OpenDrive root 'rclone-test-pixovif9memi': poll-interval is not supported by this remote 2025/09/30 06:00:01 NOTICE: OpenDrive root 'rclone-test-pixovif9memi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/30 06:00:02 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/09/30 06:00:04 DEBUG : WaitForWriters: timeout=30s 2025/09/30 06:00:04 DEBUG : dir: Looking for writers 2025/09/30 06:00:04 DEBUG : file1: reading active writers 2025/09/30 06:00:04 DEBUG : Looking for writers 2025/09/30 06:00:04 DEBUG : dir: reading active writers 2025/09/30 06:00:04 DEBUG : >WaitForWriters: 2025/09/30 06:00:05 INFO : dir/file1: Moved (server-side) to: newLeaf 2025/09/30 06:00:05 DEBUG : newLeaf: Updating file with newLeaf 0xc0008fa180 2025/09/30 06:00:05 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/09/30 06:00:05 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2025/09/30 06:00:06 INFO : newLeaf: Moved (server-side) to: dir/file1 2025/09/30 06:00:06 DEBUG : dir/file1: Updating file with dir/file1 0xc0008fa180 2025/09/30 06:00:06 DEBUG : Added virtual directory entry vDel: "newLeaf" 2025/09/30 06:00:06 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/09/30 06:00:06 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/09/30 06:00:06 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/09/30 06:00:06 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/09/30 06:00:06 DEBUG : dir/file1: File is currently open, delaying rename 0xc0008fa180 2025/09/30 06:00:06 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/09/30 06:00:06 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2025/09/30 06:00:06 DEBUG : OpenDrive root 'rclone-test-pixovif9memi': File to upload is small (25 bytes), uploading instead of streaming 2025/09/30 06:00:07 DEBUG : dir/file1: Uploading chunk 0, size=25, remain=0 2025/09/30 06:00:08 DEBUG : dir/file1: md5 = c7247bead226dfd7bd970c17229f4044 OK 2025/09/30 06:00:08 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2025/09/30 06:00:08 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2025/09/30 06:00:08 DEBUG : newLeaf: Running delayed rename now 2025/09/30 06:00:09 INFO : dir/file1: Moved (server-side) to: newLeaf 2025/09/30 06:00:09 DEBUG : newLeaf: Updating file with newLeaf 0xc0008fa180 2025/09/30 06:00:09 DEBUG : WaitForWriters: timeout=30s 2025/09/30 06:00:09 DEBUG : dir: Looking for writers 2025/09/30 06:00:09 DEBUG : Looking for writers 2025/09/30 06:00:09 DEBUG : dir: reading active writers 2025/09/30 06:00:09 DEBUG : newLeaf: reading active writers 2025/09/30 06:00:09 DEBUG : >WaitForWriters: 2025/09/30 06:00:09 DEBUG : WaitForWriters: timeout=30s 2025/09/30 06:00:09 DEBUG : dir: Looking for writers 2025/09/30 06:00:09 DEBUG : Looking for writers 2025/09/30 06:00:09 DEBUG : dir: reading active writers 2025/09/30 06:00:09 DEBUG : newLeaf: reading active writers 2025/09/30 06:00:09 DEBUG : >WaitForWriters: --- PASS: TestFileRename (9.13s) --- PASS: TestFileRename/off,forceCache=false (9.10s) FAIL 2025/09/30 06:00:10 DEBUG : OpenDrive root 'rclone-test-pixovif9memi': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirRemoveName)$|^TestFileRename$/^off,forceCache=false$'" - Finished ERROR in 48.806387216s (try 5/5): exit status 1: Failed [TestDirCreate TestDirRemoveName]