"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirMkdirSub|TestDirRemoveAll|TestDirWalk|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek)$|^TestFileRename$/^off,forceCache=false$'" - Starting (try 3/5)
2026/03/11 02:33:43 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rumotex2cebe"
2026/03/11 02:33:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/11 02:33:44 DEBUG : Starting OpenDrive session with ID: 2a9005eeef2367c4631bb04f51d1c9c038b09fb8153b73f3124bbe0aaedf6ed1
2026/03/11 02:33:44 DEBUG : Creating backend with remote "/tmp/rclone1518749514"
=== RUN   TestDirWalk
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:33:44 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:33:44 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/03/11 02:33:46 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
    run.go:290: Failed to put "fil/a/b/c" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to get folder list: Directory doesn't exist (Error 404)
2026/03/11 02:33:48 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:33:48 DEBUG : dir: Looking for writers
2026/03/11 02:33:48 DEBUG : Looking for writers
2026/03/11 02:33:48 DEBUG : dir: reading active writers
2026/03/11 02:33:48 DEBUG : >WaitForWriters: 
--- FAIL: TestDirWalk (5.74s)
=== RUN   TestDirMkdirSub
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:33:50 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:33:50 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/03/11 02:33:51 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2026/03/11 02:33:53 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2026/03/11 02:33:54 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub"
2026/03/11 02:33:54 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:33:54 DEBUG : dir/sub/subsub: Looking for writers
2026/03/11 02:33:54 DEBUG : dir/sub: Looking for writers
2026/03/11 02:33:54 DEBUG : subsub: reading active writers
2026/03/11 02:33:54 DEBUG : dir: Looking for writers
2026/03/11 02:33:54 DEBUG : file1: reading active writers
2026/03/11 02:33:54 DEBUG : sub: reading active writers
2026/03/11 02:33:54 DEBUG : Looking for writers
2026/03/11 02:33:54 DEBUG : dir: reading active writers
2026/03/11 02:33:54 DEBUG : >WaitForWriters: 
--- PASS: TestDirMkdirSub (6.86s)
=== RUN   TestDirRemoveAll
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:33:57 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:33:57 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/03/11 02:33:58 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2026/03/11 02:34:00 DEBUG : dir/file1: Remove: 
2026/03/11 02:34:00 DEBUG : dir: Added virtual directory entry vDel: "file1"
2026/03/11 02:34:00 DEBUG : dir/file1: >Remove: err=<nil>
2026/03/11 02:34:00 ERROR : dir/: Dir.Remove failed to remove directory: folder not empty
    dir_test.go:481: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:481
        	Error:      	Received unexpected error:
        	            	folder not empty
        	Test:       	TestDirRemoveAll
2026/03/11 02:34:00 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:00 DEBUG : dir: Looking for writers
2026/03/11 02:34:00 DEBUG : Looking for writers
2026/03/11 02:34:00 DEBUG : dir: reading active writers
2026/03/11 02:34:00 DEBUG : >WaitForWriters: 
--- FAIL: TestDirRemoveAll (4.27s)
=== RUN   TestFileRename
=== RUN   TestFileRename/off,forceCache=false
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:01 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:01 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/03/11 02:34:02 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0
2026/03/11 02:34:04 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:04 DEBUG : dir: Looking for writers
2026/03/11 02:34:04 DEBUG : file1: reading active writers
2026/03/11 02:34:04 DEBUG : Looking for writers
2026/03/11 02:34:04 DEBUG : dir: reading active writers
2026/03/11 02:34:04 DEBUG : >WaitForWriters: 
2026/03/11 02:34:06 INFO  : dir/file1: Moved (server-side) to: newLeaf
2026/03/11 02:34:06 DEBUG : newLeaf: Updating file with newLeaf 0xc000a240c0
2026/03/11 02:34:06 DEBUG : dir: Added virtual directory entry vDel: "file1"
2026/03/11 02:34:06 DEBUG : Added virtual directory entry vAddFile: "newLeaf"
    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/03/11 02:34:14 INFO  : newLeaf: Moved (server-side) to: dir/file1
2026/03/11 02:34:14 DEBUG : dir/file1: Updating file with dir/file1 0xc000a240c0
2026/03/11 02:34:14 DEBUG : Added virtual directory entry vDel: "newLeaf"
2026/03/11 02:34:14 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
    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/03/11 02:34:22 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2026/03/11 02:34:22 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err=<nil>
2026/03/11 02:34:22 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2026/03/11 02:34:22 DEBUG : dir/file1: File is currently open, delaying rename 0xc000a240c0
2026/03/11 02:34:22 DEBUG : dir: Added virtual directory entry vDel: "file1"
2026/03/11 02:34:22 DEBUG : Added virtual directory entry vAddFile: "newLeaf"
2026/03/11 02:34:22 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': File to upload is small (25 bytes), uploading instead of streaming
2026/03/11 02:34:22 DEBUG : dir/file1: Uploading chunk 0, size=25, remain=0
2026/03/11 02:34:23 DEBUG : dir/file1: size = 25 OK
2026/03/11 02:34:23 DEBUG : dir/file1: md5 = c7247bead226dfd7bd970c17229f4044 OK
2026/03/11 02:34:23 DEBUG : dir/file1: Size and md5 of src and dst objects identical
2026/03/11 02:34:23 DEBUG : Added virtual directory entry vAddFile: "newLeaf"
2026/03/11 02:34:23 DEBUG : newLeaf: Running delayed rename now
2026/03/11 02:34:24 INFO  : dir/file1: Moved (server-side) to: newLeaf
2026/03/11 02:34:24 DEBUG : newLeaf: Updating file with newLeaf 0xc000a240c0
2026/03/11 02:34:24 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:24 DEBUG : dir: Looking for writers
2026/03/11 02:34:24 DEBUG : Looking for writers
2026/03/11 02:34:24 DEBUG : dir: reading active writers
2026/03/11 02:34:24 DEBUG : newLeaf: reading active writers
2026/03/11 02:34:24 DEBUG : >WaitForWriters: 
2026/03/11 02:34:24 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:24 DEBUG : dir: Looking for writers
2026/03/11 02:34:24 DEBUG : Looking for writers
2026/03/11 02:34:24 DEBUG : dir: reading active writers
2026/03/11 02:34:24 DEBUG : newLeaf: reading active writers
2026/03/11 02:34:24 DEBUG : >WaitForWriters: 
--- PASS: TestFileRename (24.46s)
    --- PASS: TestFileRename/off,forceCache=false (24.46s)
=== RUN   TestReadFileHandleMethods
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:25 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:25 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/03/11 02:34:26 DEBUG : dir/file1: Uploading chunk 0, size=16, remain=0
2026/03/11 02:34:28 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2026/03/11 02:34:28 DEBUG : dir/file1: Open: flags=O_RDONLY
2026/03/11 02:34:28 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err=<nil>
2026/03/11 02:34:28 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err=<nil>
2026/03/11 02:34:28 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728
2026/03/11 02:34:29 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728
2026/03/11 02:34:29 DEBUG : dir/file1: ChunkedReader.Read at 1 length 256 chunkOffset 0 chunkSize 134217728
2026/03/11 02:34:29 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:29 DEBUG : dir: Looking for writers
2026/03/11 02:34:29 DEBUG : file1: reading active writers
2026/03/11 02:34:29 DEBUG : Looking for writers
2026/03/11 02:34:29 DEBUG : dir: reading active writers
2026/03/11 02:34:29 DEBUG : >WaitForWriters: 
--- PASS: TestReadFileHandleMethods (4.51s)
=== RUN   TestReadFileHandleSeek
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:30 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:30 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/03/11 02:34:31 DEBUG : dir/file1: Uploading chunk 0, size=16, remain=0
2026/03/11 02:34:32 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2026/03/11 02:34:33 DEBUG : dir/file1: Open: flags=O_RDONLY
2026/03/11 02:34:33 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err=<nil>
2026/03/11 02:34:33 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err=<nil>
2026/03/11 02:34:33 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728
2026/03/11 02:34:33 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728
2026/03/11 02:34:33 DEBUG : dir/file1: waiting for in-sequence read to 5 for 20ms
2026/03/11 02:34:33 DEBUG : dir/file1: aborting in-sequence read wait, off=5
2026/03/11 02:34:33 DEBUG : dir/file1: failed to wait for in-sequence read to 5
2026/03/11 02:34:33 DEBUG : dir/file1: ReadFileHandle.seek from 1 to 5 (fs.RangeSeeker)
2026/03/11 02:34:33 DEBUG : dir/file1: ChunkedReader.RangeSeek from 1 to 5 length -1
2026/03/11 02:34:33 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 5 chunkSize 134217728
2026/03/11 02:34:33 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728
2026/03/11 02:34:33 DEBUG : dir/file1: ReadFileHandle.seek from 6 to 3 (fs.RangeSeeker)
2026/03/11 02:34:33 DEBUG : dir/file1: ChunkedReader.RangeSeek from 6 to 3 length -1
2026/03/11 02:34:33 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 3 chunkSize 134217728
2026/03/11 02:34:33 DEBUG : dir/file1: ChunkedReader.openRange at 3 length 134217728
2026/03/11 02:34:34 DEBUG : dir/file1: ReadFileHandle.seek from 4 to 13 (fs.RangeSeeker)
2026/03/11 02:34:34 DEBUG : dir/file1: ChunkedReader.RangeSeek from 4 to 13 length -1
2026/03/11 02:34:34 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 13 chunkSize 134217728
2026/03/11 02:34:34 DEBUG : dir/file1: ChunkedReader.openRange at 13 length 134217728
2026/03/11 02:34:34 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:34 DEBUG : dir: Looking for writers
2026/03/11 02:34:34 DEBUG : file1: reading active writers
2026/03/11 02:34:34 DEBUG : Looking for writers
2026/03/11 02:34:34 DEBUG : dir: reading active writers
2026/03/11 02:34:34 DEBUG : >WaitForWriters: 
--- PASS: TestReadFileHandleSeek (5.55s)
=== RUN   TestReadFileHandleReadAt
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:35 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:35 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/03/11 02:34:37 DEBUG : dir/file1: Uploading chunk 0, size=16, remain=0
2026/03/11 02:34:38 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2026/03/11 02:34:38 DEBUG : dir/file1: Open: flags=O_RDONLY
2026/03/11 02:34:38 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err=<nil>
2026/03/11 02:34:38 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err=<nil>
2026/03/11 02:34:38 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728
2026/03/11 02:34:38 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728
2026/03/11 02:34:38 DEBUG : dir/file1: waiting for in-sequence read to 5 for 20ms
2026/03/11 02:34:38 DEBUG : dir/file1: aborting in-sequence read wait, off=5
2026/03/11 02:34:38 DEBUG : dir/file1: failed to wait for in-sequence read to 5
2026/03/11 02:34:38 DEBUG : dir/file1: ReadFileHandle.seek from 1 to 5 (fs.RangeSeeker)
2026/03/11 02:34:38 DEBUG : dir/file1: ChunkedReader.RangeSeek from 1 to 5 length -1
2026/03/11 02:34:38 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 5 chunkSize 134217728
2026/03/11 02:34:38 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728
2026/03/11 02:34:39 DEBUG : dir/file1: ReadFileHandle.seek from 6 to 1 (fs.RangeSeeker)
2026/03/11 02:34:39 DEBUG : dir/file1: ChunkedReader.RangeSeek from 6 to 1 length -1
2026/03/11 02:34:39 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 1 chunkSize 134217728
2026/03/11 02:34:39 DEBUG : dir/file1: ChunkedReader.openRange at 1 length 134217728
2026/03/11 02:34:39 DEBUG : dir/file1: waiting for in-sequence read to 10 for 20ms
2026/03/11 02:34:39 DEBUG : dir/file1: aborting in-sequence read wait, off=10
2026/03/11 02:34:39 DEBUG : dir/file1: failed to wait for in-sequence read to 10
2026/03/11 02:34:39 DEBUG : dir/file1: ReadFileHandle.seek from 2 to 10 (fs.RangeSeeker)
2026/03/11 02:34:39 DEBUG : dir/file1: ChunkedReader.RangeSeek from 2 to 10 length -1
2026/03/11 02:34:39 DEBUG : dir/file1: ChunkedReader.Read at -1 length 6 chunkOffset 10 chunkSize 134217728
2026/03/11 02:34:39 DEBUG : dir/file1: ChunkedReader.openRange at 10 length 134217728
2026/03/11 02:34:40 DEBUG : dir/file1: ReadFileHandle.seek from 16 to 10 (fs.RangeSeeker)
2026/03/11 02:34:40 DEBUG : dir/file1: ChunkedReader.RangeSeek from 16 to 10 length -1
2026/03/11 02:34:40 DEBUG : dir/file1: ChunkedReader.Read at -1 length 256 chunkOffset 10 chunkSize 134217728
2026/03/11 02:34:40 DEBUG : dir/file1: ChunkedReader.openRange at 10 length 134217728
2026/03/11 02:34:40 DEBUG : dir/file1: waiting for in-sequence read to 100 for 20ms
2026/03/11 02:34:40 DEBUG : dir/file1: aborting in-sequence read wait, off=100
2026/03/11 02:34:40 DEBUG : dir/file1: failed to wait for in-sequence read to 100
2026/03/11 02:34:40 DEBUG : dir/file1: ReadFileHandle.Read attempt to read beyond end of file: 100 > 16
2026/03/11 02:34:40 DEBUG : dir/file1: waiting for in-sequence read to 100 for 20ms
2026/03/11 02:34:40 DEBUG : dir/file1: aborting in-sequence read wait, off=100
2026/03/11 02:34:40 DEBUG : dir/file1: failed to wait for in-sequence read to 100
2026/03/11 02:34:40 ERROR : dir/file1: ReadFileHandle.Read error: Bad file descriptor
2026/03/11 02:34:40 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:40 DEBUG : dir: Looking for writers
2026/03/11 02:34:40 DEBUG : file1: reading active writers
2026/03/11 02:34:40 DEBUG : Looking for writers
2026/03/11 02:34:40 DEBUG : dir: reading active writers
2026/03/11 02:34:40 DEBUG : >WaitForWriters: 
    run.go:130: removing dir "dir" failed - try 1/3: folder not empty
--- PASS: TestReadFileHandleReadAt (7.08s)
=== RUN   TestReadFileHandleFlush
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:42 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:42 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:43 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:43 DEBUG : Looking for writers
2026/03/11 02:34:43 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleFlush (0.82s)
=== RUN   TestReadFileHandleRelease
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:43 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:43 NOTICE: OpenDrive root 'rclone-test-rumotex2cebe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:44 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:44 DEBUG : Looking for writers
2026/03/11 02:34:44 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleRelease (0.64s)
=== RUN   TestRWFileHandleMethodsRead
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:44 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:44 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: root is "/home/rclone/.cache/rclone"
2026/03/11 02:34:44 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:44 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:44 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:44 DEBUG : Config file has changed externally - reloading
2026/03/11 02:34:44 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:44 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:44 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:44 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:44 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:44 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:44 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:44 DEBUG : Looking for writers
2026/03/11 02:34:44 DEBUG : >WaitForWriters: 
2026/03/11 02:34:44 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleMethodsRead (0.65s)
=== RUN   TestRWFileHandleSeek
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:45 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:45 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: root is "/home/rclone/.cache/rclone"
2026/03/11 02:34:45 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:45 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:45 DEBUG : Looking for writers
2026/03/11 02:34:45 DEBUG : >WaitForWriters: 
2026/03/11 02:34:45 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleSeek (0.65s)
=== RUN   TestRWFileHandleReadAt
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:45 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:45 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: root is "/home/rclone/.cache/rclone"
2026/03/11 02:34:45 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:45 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:46 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:46 DEBUG : Looking for writers
2026/03/11 02:34:46 DEBUG : >WaitForWriters: 
2026/03/11 02:34:46 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleReadAt (0.66s)
=== RUN   TestRWFileHandleFlushRead
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:46 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:46 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: root is "/home/rclone/.cache/rclone"
2026/03/11 02:34:46 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:46 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:46 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:46 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:46 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:46 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:46 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:46 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:46 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:46 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:46 DEBUG : Looking for writers
2026/03/11 02:34:46 DEBUG : >WaitForWriters: 
2026/03/11 02:34:46 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleFlushRead (0.65s)
=== RUN   TestRWFileHandleReleaseRead
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:47 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:47 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: root is "/home/rclone/.cache/rclone"
2026/03/11 02:34:47 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:47 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:47 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:47 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:47 DEBUG : Looking for writers
2026/03/11 02:34:47 DEBUG : >WaitForWriters: 
2026/03/11 02:34:47 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleReleaseRead (0.65s)
=== RUN   TestRWFileHandleSizeTruncateExisting
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:47 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:47 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: root is "/home/rclone/.cache/rclone"
2026/03/11 02:34:47 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:47 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:47 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:47 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:48 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:48 DEBUG : Looking for writers
2026/03/11 02:34:48 DEBUG : >WaitForWriters: 
2026/03/11 02:34:48 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleSizeTruncateExisting (0.65s)
=== RUN   TestRWFileHandleSizeCreateExisting
    run.go:185: Remote "OpenDrive root 'rclone-test-rumotex2cebe'", Local "Local file system at /tmp/rclone1518749514", Modify Window "1s"
2026/03/11 02:34:48 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': poll-interval is not supported by this remote
2026/03/11 02:34:48 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: root is "/home/rclone/.cache/rclone"
2026/03/11 02:34:48 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:48 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:48 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:48 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:48 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:48 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:48 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2026/03/11 02:34:48 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-rumotex2cebe"
2026/03/11 02:34:48 INFO  : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:290: Failed to put "dir/file1" to "OpenDrive root 'rclone-test-rumotex2cebe'": failed to create file: Parent folder is trashed (Error 403)
2026/03/11 02:34:48 DEBUG : WaitForWriters: timeout=30s
2026/03/11 02:34:48 DEBUG : Looking for writers
2026/03/11 02:34:48 DEBUG : >WaitForWriters: 
2026/03/11 02:34:48 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleSizeCreateExisting (0.65s)
FAIL
2026/03/11 02:34:49 DEBUG : OpenDrive root 'rclone-test-rumotex2cebe': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirMkdirSub|TestDirRemoveAll|TestDirWalk|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek)$|^TestFileRename$/^off,forceCache=false$'" - Finished ERROR in 1m5.915062004s (try 3/5): exit status 1: Failed [TestDirWalk TestDirRemoveAll TestReadFileHandleFlush TestReadFileHandleRelease TestRWFileHandleMethodsRead TestRWFileHandleSeek TestRWFileHandleReadAt TestRWFileHandleFlushRead TestRWFileHandleReleaseRead TestRWFileHandleSizeTruncateExisting TestRWFileHandleSizeCreateExisting]