"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^TestFileRename$/^minimal,forceCache=false$|^TestFileSetModTime$/^cache=full,open=false,write=false$|^TestWriteFileHandleMethods$'" - Starting (try 2/5) 2025/02/02 05:38:28 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-nituley4coci" 2025/02/02 05:38:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/02 05:38:29 DEBUG : Creating backend with remote "/tmp/rclone2306042376" === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:180: Remote "OneDrive root 'rclone-test-nituley4coci'", Local "Local file system at /tmp/rclone2306042376", Modify Window "1s" 2025/02/02 05:38:29 DEBUG : OneDrive root 'rclone-test-nituley4coci': Next delta token is: aTE09NjM4NzQwNzE1MDkxNDc7SUQ9NzFBOTY3OThFN0IxRDI1MyExMDE7TFI9NjM4NzQwNzE1MDkyMDc7RVA9MjA7U0k9Njc7RExFUD0wO1NHPTE7U089NjtQST0z 2025/02/02 05:38:29 DEBUG : OneDrive root 'rclone-test-nituley4coci': vfs cache: root is "/home/rclone/.cache/rclone" 2025/02/02 05:38:29 DEBUG : OneDrive root 'rclone-test-nituley4coci': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:29 DEBUG : OneDrive root 'rclone-test-nituley4coci': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:29 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:29 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name 2025/02/02 05:38:29 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-nituley4coci" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:29 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:29 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name 2025/02/02 05:38:29 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestOneDrive/rclone-test-nituley4coci" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:29 INFO : OneDrive root 'rclone-test-nituley4coci': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2025/02/02 05:38:29 DEBUG : dir/file1: Starting multipart upload 2025/02/02 05:38:29 DEBUG : dir/file1: Uploading segment 0/14 size 14 2025/02/02 05:38:30 DEBUG : time_test: Starting multipart upload 2025/02/02 05:38:30 DEBUG : time_test: Uploading segment 0/5 size 5 2025/02/02 05:38:31 DEBUG : Can set mod time: true 2025/02/02 05:38:31 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/02/02 05:38:32 DEBUG : WaitForWriters: timeout=30s 2025/02/02 05:38:32 DEBUG : dir: Looking for writers 2025/02/02 05:38:32 DEBUG : file1: reading active writers 2025/02/02 05:38:32 DEBUG : : Looking for writers 2025/02/02 05:38:32 DEBUG : dir: reading active writers 2025/02/02 05:38:32 DEBUG : >WaitForWriters: 2025/02/02 05:38:32 DEBUG : OneDrive root 'rclone-test-nituley4coci': vfs cache: cleaner exiting --- PASS: TestFileSetModTime (4.11s) --- PASS: TestFileSetModTime/cache=full,open=false,write=false (4.11s) === RUN TestFileRename === RUN TestFileRename/minimal,forceCache=false run.go:180: Remote "OneDrive root 'rclone-test-nituley4coci'", Local "Local file system at /tmp/rclone2306042376", Modify Window "1s" 2025/02/02 05:38:33 DEBUG : OneDrive root 'rclone-test-nituley4coci': Next delta token is: aTE09NjM4NzQwNzE1MTMyMTM7SUQ9NzFBOTY3OThFN0IxRDI1MyExMDE7TFI9NjM4NzQwNzE1MTMzMTA7RVA9MjA7U0k9MjI7RExFUD0wO1NHPTE7U089NjtQST0z 2025/02/02 05:38:33 NOTICE: OneDrive root 'rclone-test-nituley4coci': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/02/02 05:38:33 DEBUG : OneDrive root 'rclone-test-nituley4coci': vfs cache: root is "/home/rclone/.cache/rclone" 2025/02/02 05:38:33 DEBUG : OneDrive root 'rclone-test-nituley4coci': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:33 DEBUG : OneDrive root 'rclone-test-nituley4coci': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:33 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:33 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name 2025/02/02 05:38:33 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-nituley4coci" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:33 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:33 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name 2025/02/02 05:38:33 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestOneDrive/rclone-test-nituley4coci" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestOneDrive/rclone-test-nituley4coci" 2025/02/02 05:38:33 INFO : OneDrive root 'rclone-test-nituley4coci': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2025/02/02 05:38:33 DEBUG : dir/file1: Starting multipart upload 2025/02/02 05:38:33 DEBUG : dir/file1: Uploading segment 0/14 size 14 2025/02/02 05:38:34 DEBUG : WaitForWriters: timeout=30s 2025/02/02 05:38:34 DEBUG : dir: Looking for writers 2025/02/02 05:38:34 DEBUG : file1: reading active writers 2025/02/02 05:38:34 DEBUG : : Looking for writers 2025/02/02 05:38:34 DEBUG : dir: reading active writers 2025/02/02 05:38:34 DEBUG : >WaitForWriters: 2025/02/02 05:38:35 INFO : dir/file1: Moved (server-side) to: newLeaf 2025/02/02 05:38:35 DEBUG : newLeaf: Updating file with newLeaf 0xc000199200 2025/02/02 05:38:35 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/02/02 05:38:35 DEBUG : : Added virtual directory entry vAddFile: "newLeaf" 2025/02/02 05:38:36 INFO : newLeaf: Moved (server-side) to: dir/file1 2025/02/02 05:38:36 DEBUG : dir/file1: Updating file with dir/file1 0xc000199200 2025/02/02 05:38:36 DEBUG : : Added virtual directory entry vDel: "newLeaf" 2025/02/02 05:38:36 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:36 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/02/02 05:38:36 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/02/02 05:38:36 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:36 DEBUG : dir/file1: File is currently open, delaying rename 0xc000199200 2025/02/02 05:38:36 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/02/02 05:38:36 DEBUG : : Added virtual directory entry vAddFile: "newLeaf" 2025/02/02 05:38:36 DEBUG : OneDrive root 'rclone-test-nituley4coci': File to upload is small (25 bytes), uploading instead of streaming 2025/02/02 05:38:36 DEBUG : dir/file1: Starting multipart upload 2025/02/02 05:38:36 DEBUG : dir/file1: Uploading segment 0/25 size 25 2025/02/02 05:38:36 DEBUG : dir/file1: quickxor = 954e6302251b5e744b33bcf26f301bca00023794 OK 2025/02/02 05:38:36 DEBUG : dir/file1: Size and quickxor of src and dst objects identical 2025/02/02 05:38:36 DEBUG : : Added virtual directory entry vAddFile: "newLeaf" 2025/02/02 05:38:36 DEBUG : newLeaf: Running delayed rename now 2025/02/02 05:38:37 INFO : dir/file1: Moved (server-side) to: newLeaf 2025/02/02 05:38:37 DEBUG : newLeaf: Updating file with newLeaf 0xc000199200 2025/02/02 05:38:37 DEBUG : WaitForWriters: timeout=30s 2025/02/02 05:38:37 DEBUG : dir: Looking for writers 2025/02/02 05:38:37 DEBUG : : Looking for writers 2025/02/02 05:38:37 DEBUG : newLeaf: reading active writers 2025/02/02 05:38:37 DEBUG : dir: reading active writers 2025/02/02 05:38:37 DEBUG : >WaitForWriters: 2025/02/02 05:38:37 DEBUG : WaitForWriters: timeout=30s 2025/02/02 05:38:37 DEBUG : dir: Looking for writers 2025/02/02 05:38:37 DEBUG : : Looking for writers 2025/02/02 05:38:37 DEBUG : dir: reading active writers 2025/02/02 05:38:37 DEBUG : newLeaf: reading active writers 2025/02/02 05:38:37 DEBUG : >WaitForWriters: 2025/02/02 05:38:37 DEBUG : OneDrive root 'rclone-test-nituley4coci': vfs cache: cleaner exiting --- PASS: TestFileRename (5.03s) --- PASS: TestFileRename/minimal,forceCache=false (5.03s) === RUN TestWriteFileHandleMethods run.go:180: Remote "OneDrive root 'rclone-test-nituley4coci'", Local "Local file system at /tmp/rclone2306042376", Modify Window "1s" 2025/02/02 05:38:38 DEBUG : OneDrive root 'rclone-test-nituley4coci': Next delta token is: aTE09NjM4NzQwNzE1MTgxNjA7SUQ9NzFBOTY3OThFN0IxRDI1MyExMDE7TFI9NjM4NzQwNzE1MTgzNjc7RVA9MjA7U0k9NjA7RExFUD0wO1NHPTE7U089NjtQST0z 2025/02/02 05:38:38 NOTICE: OneDrive root 'rclone-test-nituley4coci': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/02/02 05:38:38 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/02/02 05:38:38 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/02/02 05:38:38 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:38 DEBUG : file1: >Open: fd=file1 (w), err= 2025/02/02 05:38:38 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/02/02 05:38:38 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:38 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2025/02/02 05:38:38 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2025/02/02 05:38:38 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2025/02/02 05:38:38 DEBUG : OneDrive root 'rclone-test-nituley4coci': File to upload is small (5 bytes), uploading instead of streaming 2025/02/02 05:38:38 DEBUG : file1: Starting multipart upload 2025/02/02 05:38:38 DEBUG : file1: Uploading segment 0/5 size 5 2025/02/02 05:38:38 DEBUG : file1: quickxor = 6828031bd8f00600000000000500000000000000 OK 2025/02/02 05:38:38 DEBUG : file1: Size and quickxor of src and dst objects identical 2025/02/02 05:38:38 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:39 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/02/02 05:38:39 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/02/02 05:38:39 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:39 DEBUG : file1: >Open: fd=file1 (w), err= 2025/02/02 05:38:39 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/02/02 05:38:39 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/02/02 05:38:39 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/02/02 05:38:39 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:39 DEBUG : file1: >Open: fd=file1 (w), err= 2025/02/02 05:38:39 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/02/02 05:38:39 ERROR : file1: WriteFileHandle: Can't open for write without O_TRUNC on existing file without --vfs-cache-mode >= writes 2025/02/02 05:38:39 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2025/02/02 05:38:39 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2025/02/02 05:38:39 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:39 DEBUG : file1: >Open: fd=file1 (w), err= 2025/02/02 05:38:39 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/02/02 05:38:39 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:39 DEBUG : OneDrive root 'rclone-test-nituley4coci': File to upload is small (0 bytes), uploading instead of streaming 2025/02/02 05:38:39 DEBUG : file1: Starting singlepart upload 2025/02/02 05:38:39 DEBUG : info from Update error: null 2025/02/02 05:38:39 ERROR : file1: WriteFileHandle.New Rcat failed: resourceModified: ETag does not match current item's value write_test.go:181: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:181 Error: Received unexpected error: resourceModified: ETag does not match current item's value Test: TestWriteFileHandleMethods dir_test.go:247: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:247 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:182 Error: Not equal: expected: []string{"file1,0,false"} actual : []string{"file1,5,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=13) "file1,0,false" + (string) (len=13) "file1,5,false" } Test: TestWriteFileHandleMethods 2025/02/02 05:38:39 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2025/02/02 05:38:39 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2025/02/02 05:38:39 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:39 DEBUG : file1: >Open: fd=file1 (w), err= 2025/02/02 05:38:39 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/02/02 05:38:39 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:39 DEBUG : OneDrive root 'rclone-test-nituley4coci': File to upload is small (7 bytes), uploading instead of streaming 2025/02/02 05:38:39 DEBUG : file1: Starting multipart upload 2025/02/02 05:38:39 DEBUG : file1: Uploading segment 0/7 size 7 2025/02/02 05:38:39 DEBUG : file1: quickxor = 6828031bd8f08618c80000000700000000000000 OK 2025/02/02 05:38:39 DEBUG : file1: Size and quickxor of src and dst objects identical 2025/02/02 05:38:39 DEBUG : : Added virtual directory entry vAddFile: "file1" 2025/02/02 05:38:39 DEBUG : WaitForWriters: timeout=30s 2025/02/02 05:38:39 DEBUG : : Looking for writers 2025/02/02 05:38:39 DEBUG : file1: reading active writers 2025/02/02 05:38:39 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleMethods (1.74s) FAIL 2025/02/02 05:38:40 DEBUG : OneDrive root 'rclone-test-nituley4coci': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^TestFileRename$/^minimal,forceCache=false$|^TestFileSetModTime$/^cache=full,open=false,write=false$|^TestWriteFileHandleMethods$'" - Finished ERROR in 11.581498995s (try 2/5): exit status 1: Failed [TestWriteFileHandleMethods]