"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirMkdir|TestDirRemoveName|TestDirRename|TestRWFileHandleSizeCreateExisting)$|^TestFileSetModTime$/^(cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Starting (try 2/5) 2026/02/06 04:57:03 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-honeruy1kuta" 2026/02/06 04:57:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 04:57:04 DEBUG : Starting OpenDrive session with ID: 859930793cf2cad0b49deb7eaf9f28946557856bbfa182568488054c0ae497a1 2026/02/06 04:57:04 DEBUG : Creating backend with remote "/tmp/rclone1368818660" === RUN TestDirCreate run.go:185: Remote "OpenDrive root 'rclone-test-honeruy1kuta'", Local "Local file system at /tmp/rclone1368818660", Modify Window "1s" 2026/02/06 04:57:04 INFO : OpenDrive root 'rclone-test-honeruy1kuta': poll-interval is not supported by this remote 2026/02/06 04:57:04 NOTICE: OpenDrive root 'rclone-test-honeruy1kuta': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/06 04:57:06 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 2026/02/06 04:57:07 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2026/02/06 04:57:07 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/02/06 04:57:07 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2026/02/06 04:57:07 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/02/06 04:57:07 DEBUG : OpenDrive root 'rclone-test-honeruy1kuta': File to upload is small (5 bytes), uploading instead of streaming 2026/02/06 04:57:08 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2026/02/06 04:57:09 DEBUG : dir/potato: size = 5 OK 2026/02/06 04:57:09 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/02/06 04:57:09 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2026/02/06 04:57:09 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/02/06 04:57:09 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:09 DEBUG : dir: Looking for writers 2026/02/06 04:57:09 DEBUG : file1: reading active writers 2026/02/06 04:57:09 DEBUG : potato: reading active writers 2026/02/06 04:57:09 DEBUG : Looking for writers 2026/02/06 04:57:09 DEBUG : dir: reading active writers 2026/02/06 04:57:09 DEBUG : >WaitForWriters: 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 --- FAIL: TestDirCreate (13.81s) === RUN TestDirMkdir run.go:185: Remote "OpenDrive root 'rclone-test-honeruy1kuta'", Local "Local file system at /tmp/rclone1368818660", Modify Window "1s" 2026/02/06 04:57:18 INFO : OpenDrive root 'rclone-test-honeruy1kuta': poll-interval is not supported by this remote 2026/02/06 04:57:18 NOTICE: OpenDrive root 'rclone-test-honeruy1kuta': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/06 04:57:19 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2026/02/06 04:57:21 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2026/02/06 04:57:22 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:22 DEBUG : dir/sub: Looking for writers 2026/02/06 04:57:22 DEBUG : dir: Looking for writers 2026/02/06 04:57:22 DEBUG : file1: reading active writers 2026/02/06 04:57:22 DEBUG : sub: reading active writers 2026/02/06 04:57:22 DEBUG : Looking for writers 2026/02/06 04:57:22 DEBUG : dir: reading active writers 2026/02/06 04:57:22 DEBUG : >WaitForWriters: --- PASS: TestDirMkdir (5.46s) === RUN TestDirRemoveName run.go:185: Remote "OpenDrive root 'rclone-test-honeruy1kuta'", Local "Local file system at /tmp/rclone1368818660", Modify Window "1s" 2026/02/06 04:57:23 INFO : OpenDrive root 'rclone-test-honeruy1kuta': poll-interval is not supported by this remote 2026/02/06 04:57:23 NOTICE: OpenDrive root 'rclone-test-honeruy1kuta': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/06 04:57:25 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:132 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /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:345 /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: dir/file1: Modification time difference too big |-219216h52m18.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-02-06 04:57:25 +0000 UTC) (precision 1s) 2026/02/06 04:57:26 DEBUG : dir/file1: Remove: 2026/02/06 04:57:27 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/02/06 04:57:27 DEBUG : dir/file1: >Remove: err= dir_test.go:504: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:504 Error: Should be true Test: TestDirRemoveName 2026/02/06 04:57:27 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:27 DEBUG : dir: Looking for writers 2026/02/06 04:57:27 DEBUG : Looking for writers 2026/02/06 04:57:27 DEBUG : dir: reading active writers 2026/02/06 04:57:27 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (4.45s) === RUN TestDirRename run.go:185: Remote "OpenDrive root 'rclone-test-honeruy1kuta'", Local "Local file system at /tmp/rclone1368818660", Modify Window "1s" 2026/02/06 04:57:28 INFO : OpenDrive root 'rclone-test-honeruy1kuta': poll-interval is not supported by this remote 2026/02/06 04:57:28 NOTICE: OpenDrive root 'rclone-test-honeruy1kuta': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/06 04:57:29 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2026/02/06 04:57:31 DEBUG : dir/file3: Uploading chunk 0, size=15, remain=0 2026/02/06 04:57:33 ERROR : dir/not found: Dir.Rename error: file does not exist 2026/02/06 04:57:33 DEBUG : dir2: same parent dir (ODFfOTg3MDcxMV82bEpIcQ) - using folder/rename instead of move_copy 2026/02/06 04:57:33 DEBUG : dir: Updating dir with dir2 0xc0008da680 2026/02/06 04:57:33 DEBUG : dir: forgetting directory cache 2026/02/06 04:57:33 DEBUG : Added virtual directory entry vDel: "dir" 2026/02/06 04:57:33 DEBUG : Added virtual directory entry vAddDir: "dir2" 2026/02/06 04:57:34 INFO : dir2/file1: Moved (server-side) to: file2 2026/02/06 04:57:34 DEBUG : file2: Updating file with file2 0xc0001aef00 2026/02/06 04:57:34 DEBUG : dir2: Added virtual directory entry vDel: "file1" 2026/02/06 04:57:34 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/02/06 04:57:35 INFO : dir2/file3: Deleted 2026/02/06 04:57:36 INFO : file2: Moved (server-side) to: dir2/file3 2026/02/06 04:57:36 DEBUG : dir2/file3: Updating file with dir2/file3 0xc0001aef00 2026/02/06 04:57:36 DEBUG : Added virtual directory entry vDel: "file2" 2026/02/06 04:57:36 DEBUG : dir2: Added virtual directory entry vAddFile: "file3" 2026/02/06 04:57:36 DEBUG : Added virtual directory entry vAddDir: "empty directory" 2026/02/06 04:57:36 DEBUG : renamed empty directory: same parent dir (ODFfOTg3MDcxMV82bEpIcQ) - using folder/rename instead of move_copy 2026/02/06 04:57:37 DEBUG : empty directory: Updating dir with renamed empty directory 0xc0008dac30 2026/02/06 04:57:37 DEBUG : empty directory: forgetting directory cache 2026/02/06 04:57:37 DEBUG : Added virtual directory entry vDel: "empty directory" 2026/02/06 04:57:37 DEBUG : Added virtual directory entry vAddDir: "renamed empty directory" 2026/02/06 04:57:37 DEBUG : dir2: Renaming to "dir3" 2026/02/06 04:57:37 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:37 DEBUG : dir3: Looking for writers 2026/02/06 04:57:37 DEBUG : file3: reading active writers 2026/02/06 04:57:37 DEBUG : renamed empty directory: Looking for writers 2026/02/06 04:57:37 DEBUG : Looking for writers 2026/02/06 04:57:37 DEBUG : dir3: reading active writers 2026/02/06 04:57:37 DEBUG : renamed empty directory: reading active writers 2026/02/06 04:57:37 DEBUG : >WaitForWriters: --- PASS: TestDirRename (10.35s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:185: Remote "OpenDrive root 'rclone-test-honeruy1kuta'", Local "Local file system at /tmp/rclone1368818660", Modify Window "1s" 2026/02/06 04:57:38 INFO : OpenDrive root 'rclone-test-honeruy1kuta': poll-interval is not supported by this remote 2026/02/06 04:57:38 NOTICE: OpenDrive root 'rclone-test-honeruy1kuta': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/06 04:57:39 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2026/02/06 04:57:42 DEBUG : time_test: Uploading chunk 0, size=5, remain=0 2026/02/06 04:57:43 DEBUG : Can set mod time: true 2026/02/06 04:57:43 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2026/02/06 04:57:43 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2026/02/06 04:57:43 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/06 04:57:43 DEBUG : OpenDrive root 'rclone-test-honeruy1kuta': File to upload is small (0 bytes), uploading instead of streaming 2026/02/06 04:57:44 DEBUG : dir/file1: size = 0 OK 2026/02/06 04:57:44 DEBUG : dir/file1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/06 04:57:44 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2026/02/06 04:57:45 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2026/02/06 04:57:45 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/06 04:57:45 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:45 DEBUG : dir: Looking for writers 2026/02/06 04:57:45 DEBUG : file1: reading active writers 2026/02/06 04:57:45 DEBUG : Looking for writers 2026/02/06 04:57:45 DEBUG : dir: reading active writers 2026/02/06 04:57:45 DEBUG : >WaitForWriters: fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:132 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /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:345 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:139 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159 Error: Should be true Test: TestFileSetModTime/cache=off,open=true,write=false Messages: dir/file1: Modification time difference too big |-123759h57m43.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-02-06 04:57:43 +0000 UTC) (precision 1s) 2026/02/06 04:57:45 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:45 DEBUG : dir: Looking for writers 2026/02/06 04:57:45 DEBUG : file1: reading active writers 2026/02/06 04:57:45 DEBUG : Looking for writers 2026/02/06 04:57:45 DEBUG : dir: reading active writers 2026/02/06 04:57:45 DEBUG : >WaitForWriters: run.go:130: removing dir "dir" failed - try 1/3: folder not empty === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:185: Remote "OpenDrive root 'rclone-test-honeruy1kuta'", Local "Local file system at /tmp/rclone1368818660", Modify Window "1s" 2026/02/06 04:57:47 INFO : OpenDrive root 'rclone-test-honeruy1kuta': poll-interval is not supported by this remote 2026/02/06 04:57:47 NOTICE: OpenDrive root 'rclone-test-honeruy1kuta': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/02/06 04:57:48 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2026/02/06 04:57:50 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2026/02/06 04:57:50 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2026/02/06 04:57:50 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/06 04:57:50 DEBUG : OpenDrive root 'rclone-test-honeruy1kuta': File to upload is small (5 bytes), uploading instead of streaming 2026/02/06 04:57:50 DEBUG : dir/file1: Uploading chunk 0, size=5, remain=0 2026/02/06 04:57:51 DEBUG : dir/file1: size = 5 OK 2026/02/06 04:57:51 DEBUG : dir/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/02/06 04:57:51 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2026/02/06 04:57:52 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2026/02/06 04:57:52 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/06 04:57:52 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:52 DEBUG : dir: Looking for writers 2026/02/06 04:57:52 DEBUG : file1: reading active writers 2026/02/06 04:57:52 DEBUG : Looking for writers 2026/02/06 04:57:52 DEBUG : dir: reading active writers 2026/02/06 04:57:52 DEBUG : >WaitForWriters: 2026/02/06 04:57:52 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:52 DEBUG : dir: Looking for writers 2026/02/06 04:57:52 DEBUG : file1: reading active writers 2026/02/06 04:57:52 DEBUG : Looking for writers 2026/02/06 04:57:52 DEBUG : dir: reading active writers 2026/02/06 04:57:52 DEBUG : >WaitForWriters: --- FAIL: TestFileSetModTime (14.80s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=false (9.08s) --- PASS: TestFileSetModTime/cache=off,open=true,write=true (5.71s) === RUN TestRWFileHandleSizeCreateExisting run.go:185: Remote "OpenDrive root 'rclone-test-honeruy1kuta'", Local "Local file system at /tmp/rclone1368818660", Modify Window "1s" 2026/02/06 04:57:53 INFO : OpenDrive root 'rclone-test-honeruy1kuta': poll-interval is not supported by this remote 2026/02/06 04:57:53 DEBUG : OpenDrive root 'rclone-test-honeruy1kuta': vfs cache: root is "/home/rclone/.cache/rclone" 2026/02/06 04:57:53 DEBUG : OpenDrive root 'rclone-test-honeruy1kuta': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-honeruy1kuta" 2026/02/06 04:57:53 DEBUG : OpenDrive root 'rclone-test-honeruy1kuta': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-honeruy1kuta" 2026/02/06 04:57:53 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-honeruy1kuta" 2026/02/06 04:57:53 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/02/06 04:57:53 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-honeruy1kuta" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-honeruy1kuta" 2026/02/06 04:57:53 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-honeruy1kuta" 2026/02/06 04:57:53 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2026/02/06 04:57:53 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-honeruy1kuta" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-honeruy1kuta" 2026/02/06 04:57:53 INFO : OpenDrive root 'rclone-test-honeruy1kuta': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2026/02/06 04:57:54 DEBUG : dir/file1: Uploading chunk 0, size=16, remain=0 2026/02/06 04:57:55 DEBUG : dir/file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/02/06 04:57:56 DEBUG : dir/file1: Open: flags=O_WRONLY|O_CREATE 2026/02/06 04:57:56 DEBUG : dir/file1: newRWFileHandle: 2026/02/06 04:57:56 DEBUG : dir/file1: >newRWFileHandle: err= 2026/02/06 04:57:56 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/06 04:57:56 DEBUG : dir/file1: >Open: fd=dir/file1 (rw), err= 2026/02/06 04:57:56 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (rw), err= 2026/02/06 04:57:56 DEBUG : dir/file1(0xc000aa0480): _writeAt: size=5, off=0 2026/02/06 04:57:56 DEBUG : dir/file1(0xc000aa0480): openPending: 2026/02/06 04:57:56 DEBUG : dir/file1: vfs cache: checking remote fingerprint "16,2001-02-03 04:05:06 +0000 UTC,4032af8d61035123906e58e067140cc5" against cached fingerprint "" 2026/02/06 04:57:56 DEBUG : dir/file1: vfs cache: truncate to size=16 2026/02/06 04:57:56 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/06 04:57:56 DEBUG : dir/file1(0xc000aa0480): >openPending: err= 2026/02/06 04:57:56 DEBUG : dir/file1(0xc000aa0480): >_writeAt: n=5, err= 2026/02/06 04:57:56 DEBUG : dir/file1(0xc000aa0480): _writeAt: size=15, off=5 2026/02/06 04:57:56 DEBUG : dir/file1(0xc000aa0480): >_writeAt: n=15, err= 2026/02/06 04:57:56 DEBUG : dir/file1(0xc000aa0480): close: 2026/02/06 04:57:56 DEBUG : vfs cache: looking for range={Pos:0 Size:20} in [{Pos:0 Size:20}] - present true 2026/02/06 04:57:56 DEBUG : dir/file1: vfs cache: setting modification time to 2026-02-06 04:57:56.313484916 +0000 UTC m=+52.643886425 2026/02/06 04:57:56 INFO : dir/file1: vfs cache: queuing for upload in 100ms 2026/02/06 04:57:56 DEBUG : dir/file1(0xc000aa0480): >close: err= 2026/02/06 04:57:56 DEBUG : WaitForWriters: timeout=30s 2026/02/06 04:57:56 DEBUG : dir: Looking for writers 2026/02/06 04:57:56 DEBUG : file1: reading active writers 2026/02/06 04:57:56 DEBUG : Looking for writers 2026/02/06 04:57:56 DEBUG : dir: reading active writers 2026/02/06 04:57:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2026/02/06 04:57:56 DEBUG : dir: Looking for writers 2026/02/06 04:57:56 DEBUG : file1: reading active writers 2026/02/06 04:57:56 DEBUG : Looking for writers 2026/02/06 04:57:56 DEBUG : dir: reading active writers 2026/02/06 04:57:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2026/02/06 04:57:56 DEBUG : dir: Looking for writers 2026/02/06 04:57:56 DEBUG : file1: reading active writers 2026/02/06 04:57:56 DEBUG : Looking for writers 2026/02/06 04:57:56 DEBUG : dir: reading active writers 2026/02/06 04:57:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2026/02/06 04:57:56 DEBUG : dir: Looking for writers 2026/02/06 04:57:56 DEBUG : file1: reading active writers 2026/02/06 04:57:56 DEBUG : Looking for writers 2026/02/06 04:57:56 DEBUG : dir: reading active writers 2026/02/06 04:57:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2026/02/06 04:57:56 DEBUG : dir/file1: vfs cache: starting upload 2026/02/06 04:57:56 DEBUG : dir: Looking for writers 2026/02/06 04:57:56 DEBUG : file1: reading active writers 2026/02/06 04:57:56 DEBUG : Looking for writers 2026/02/06 04:57:56 DEBUG : dir: reading active writers 2026/02/06 04:57:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2026/02/06 04:57:56 DEBUG : dir/file1: Uploading chunk 0, size=20, remain=0 2026/02/06 04:57:56 DEBUG : dir: Looking for writers 2026/02/06 04:57:56 DEBUG : file1: reading active writers 2026/02/06 04:57:56 DEBUG : Looking for writers 2026/02/06 04:57:56 DEBUG : dir: reading active writers 2026/02/06 04:57:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2026/02/06 04:57:56 DEBUG : dir: Looking for writers 2026/02/06 04:57:56 DEBUG : file1: reading active writers 2026/02/06 04:57:56 DEBUG : Looking for writers 2026/02/06 04:57:56 DEBUG : dir: reading active writers 2026/02/06 04:57:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2026/02/06 04:57:57 DEBUG : dir/file1: size = 20 OK 2026/02/06 04:57:57 DEBUG : dir/file1: md5 = 5198435b5c4e591523b7f5435cc07e88 OK 2026/02/06 04:57:57 INFO : dir/file1: Copied (replaced existing) 2026/02/06 04:57:57 DEBUG : dir/file1: vfs cache: fingerprint now "20,2026-02-06 04:57:56 +0000 UTC,5198435b5c4e591523b7f5435cc07e88" 2026/02/06 04:57:57 DEBUG : dir/file1: vfs cache: writeback object to VFS layer 2026/02/06 04:57:57 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2026/02/06 04:57:57 INFO : dir/file1: vfs cache: upload succeeded try #1 2026/02/06 04:57:57 DEBUG : dir: Looking for writers 2026/02/06 04:57:57 DEBUG : file1: reading active writers 2026/02/06 04:57:57 DEBUG : Looking for writers 2026/02/06 04:57:57 DEBUG : dir: reading active writers 2026/02/06 04:57:57 DEBUG : >WaitForWriters: 2026/02/06 04:57:57 DEBUG : OpenDrive root 'rclone-test-honeruy1kuta': vfs cache: cleaner exiting --- PASS: TestRWFileHandleSizeCreateExisting (5.19s) FAIL 2026/02/06 04:57:58 DEBUG : OpenDrive root 'rclone-test-honeruy1kuta': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirMkdir|TestDirRemoveName|TestDirRename|TestRWFileHandleSizeCreateExisting)$|^TestFileSetModTime$/^(cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Finished ERROR in 55.347205619s (try 2/5): exit status 1: Failed [TestDirCreate TestDirRemoveName TestFileSetModTime/cache=off,open=true,write=false]