"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestDirCreate$|^TestFileRename$/^minimal,forceCache=false$'" - Starting (try 4/5) 2025/07/27 04:09:41 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zufajed2cazu" 2025/07/27 04:09:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/27 04:09:41 DEBUG : Starting OpenDrive session with ID: c0c827c6a01f40c8177b3e403074c576ee69c6ea96aeb04674649ca3defe2be6 2025/07/27 04:09:42 DEBUG : Creating backend with remote "/tmp/rclone3773275603" === RUN TestDirCreate run.go:180: Remote "OpenDrive root 'rclone-test-zufajed2cazu'", Local "Local file system at /tmp/rclone3773275603", Modify Window "1s" 2025/07/27 04:09:42 INFO : OpenDrive root 'rclone-test-zufajed2cazu': poll-interval is not supported by this remote 2025/07/27 04:09:42 NOTICE: OpenDrive root 'rclone-test-zufajed2cazu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/07/27 04:09:43 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/07/27 04:09:45 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/07/27 04:09:45 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/07/27 04:09:45 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/07/27 04:09:45 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/07/27 04:09:45 DEBUG : OpenDrive root 'rclone-test-zufajed2cazu': File to upload is small (5 bytes), uploading instead of streaming 2025/07/27 04:09:46 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2025/07/27 04:09:47 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/07/27 04:09:47 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2025/07/27 04:09:47 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/07/27 04:09:47 DEBUG : WaitForWriters: timeout=30s 2025/07/27 04:09:47 DEBUG : dir: Looking for writers 2025/07/27 04:09:47 DEBUG : potato: reading active writers 2025/07/27 04:09:47 DEBUG : file1: reading active writers 2025/07/27 04:09:47 DEBUG : Looking for writers 2025/07/27 04:09:47 DEBUG : dir: reading active writers 2025/07/27 04:09:47 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (6.26s) === RUN TestFileRename === RUN TestFileRename/minimal,forceCache=false run.go:180: Remote "OpenDrive root 'rclone-test-zufajed2cazu'", Local "Local file system at /tmp/rclone3773275603", Modify Window "1s" 2025/07/27 04:09:48 INFO : OpenDrive root 'rclone-test-zufajed2cazu': poll-interval is not supported by this remote 2025/07/27 04:09:48 NOTICE: OpenDrive root 'rclone-test-zufajed2cazu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/07/27 04:09:48 DEBUG : OpenDrive root 'rclone-test-zufajed2cazu': vfs cache: root is "/home/rclone/.cache/rclone" 2025/07/27 04:09:48 DEBUG : OpenDrive root 'rclone-test-zufajed2cazu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-zufajed2cazu" 2025/07/27 04:09:48 DEBUG : OpenDrive root 'rclone-test-zufajed2cazu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-zufajed2cazu" 2025/07/27 04:09:48 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-zufajed2cazu" 2025/07/27 04:09:48 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/27 04:09:48 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-zufajed2cazu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-zufajed2cazu" 2025/07/27 04:09:48 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-zufajed2cazu" 2025/07/27 04:09:48 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/07/27 04:09:48 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-zufajed2cazu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-zufajed2cazu" 2025/07/27 04:09:48 INFO : OpenDrive root 'rclone-test-zufajed2cazu': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2025/07/27 04:09:49 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/07/27 04:09:51 DEBUG : WaitForWriters: timeout=30s 2025/07/27 04:09:51 DEBUG : dir: Looking for writers 2025/07/27 04:09:51 DEBUG : file1: reading active writers 2025/07/27 04:09:51 DEBUG : Looking for writers 2025/07/27 04:09:51 DEBUG : dir: reading active writers 2025/07/27 04:09:51 DEBUG : >WaitForWriters: 2025/07/27 04:09:52 INFO : dir/file1: Moved (server-side) to: newLeaf 2025/07/27 04:09:52 DEBUG : newLeaf: Updating file with newLeaf 0xc000a30180 2025/07/27 04:09:52 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/07/27 04:09:52 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2025/07/27 04:09:53 INFO : newLeaf: Moved (server-side) to: dir/file1 2025/07/27 04:09:53 DEBUG : dir/file1: Updating file with dir/file1 0xc000a30180 2025/07/27 04:09:53 DEBUG : Added virtual directory entry vDel: "newLeaf" 2025/07/27 04:09:53 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/07/27 04:09:53 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/07/27 04:09:53 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/07/27 04:09:53 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/07/27 04:09:53 DEBUG : dir/file1: File is currently open, delaying rename 0xc000a30180 2025/07/27 04:09:53 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/07/27 04:09:53 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2025/07/27 04:09:53 DEBUG : OpenDrive root 'rclone-test-zufajed2cazu': File to upload is small (25 bytes), uploading instead of streaming 2025/07/27 04:09:53 DEBUG : dir/file1: Uploading chunk 0, size=25, remain=0 2025/07/27 04:09:55 DEBUG : dir/file1: md5 = c7247bead226dfd7bd970c17229f4044 OK 2025/07/27 04:09:55 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2025/07/27 04:09:55 DEBUG : Added virtual directory entry vAddFile: "newLeaf" 2025/07/27 04:09:55 DEBUG : newLeaf: Running delayed rename now 2025/07/27 04:09:55 INFO : dir/file1: Moved (server-side) to: newLeaf 2025/07/27 04:09:55 DEBUG : newLeaf: Updating file with newLeaf 0xc000a30180 2025/07/27 04:09:55 DEBUG : WaitForWriters: timeout=30s 2025/07/27 04:09:55 DEBUG : dir: Looking for writers 2025/07/27 04:09:55 DEBUG : Looking for writers 2025/07/27 04:09:55 DEBUG : newLeaf: reading active writers 2025/07/27 04:09:55 DEBUG : dir: reading active writers 2025/07/27 04:09:55 DEBUG : >WaitForWriters: 2025/07/27 04:09:56 DEBUG : WaitForWriters: timeout=30s 2025/07/27 04:09:56 DEBUG : dir: Looking for writers 2025/07/27 04:09:56 DEBUG : Looking for writers 2025/07/27 04:09:56 DEBUG : dir: reading active writers 2025/07/27 04:09:56 DEBUG : newLeaf: reading active writers 2025/07/27 04:09:56 DEBUG : >WaitForWriters: 2025/07/27 04:09:56 DEBUG : OpenDrive root 'rclone-test-zufajed2cazu': vfs cache: cleaner exiting fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Flushing the directory cache fstest.go:289: Sleeping for 7s just to make sure --- PASS: TestFileRename (19.49s) --- PASS: TestFileRename/minimal,forceCache=false (19.49s) FAIL 2025/07/27 04:10:08 DEBUG : OpenDrive root 'rclone-test-zufajed2cazu': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestDirCreate$|^TestFileRename$/^minimal,forceCache=false$'" - Finished ERROR in 27.023263742s (try 4/5): exit status 1: Failed [TestDirCreate]