"./vfs.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestCaseSensitivity|TestDirCreate|TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMethods|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestFileRemove|TestFileRemoveAll|TestRWCacheUpdate|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleMethodsWrite|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestRWFileHandleWriteAt|TestRWFileHandleWriteNoWrite|TestRWFileModTimeWithOpenWriters|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleRelease|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters)$|^TestFileRename$/^(full,forceCache=false|minimal,forceCache=false|minimal,forceCache=true|off,forceCache=false|writes,forceCache=false|writes,forceCache=true)$|^TestFileSetModTime$/^(cache=full,open=false,write=false|cache=full,open=true,write=false|cache=full,open=true,write=true|cache=off,open=false,write=false|cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Starting (try 4/5) 2024/04/25 05:21:11 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:21:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/25 05:21:11 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': Has cached credentials 2024/04/25 05:21:12 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': Used cached credential to initialize the ProtonDrive API 2024/04/25 05:21:13 DEBUG : Creating backend with remote "/tmp/rclone808050284" === RUN TestDirHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:13 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:13 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:15.336688 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:15.336728 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:15 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:15 DEBUG : : Looking for writers 2024/04/25 05:21:15 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleMethods (3.54s) === RUN TestDirHandleReaddir run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:16 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:16 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:17.991665 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:17.991699 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:17 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:17 DEBUG : : Looking for writers 2024/04/25 05:21:17 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddir (2.68s) === RUN TestDirHandleReaddirnames run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:19 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:19 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:20.723355 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:20.723397 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:20 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:20 DEBUG : : Looking for writers 2024/04/25 05:21:20 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddirnames (2.71s) === RUN TestDirMethods run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:22 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:22 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:23.375844 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:23.375875 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:23 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:23 DEBUG : : Looking for writers 2024/04/25 05:21:23 DEBUG : >WaitForWriters: --- FAIL: TestDirMethods (2.67s) === RUN TestDirForgetAll run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:24 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:24 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:26.034617 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:26.034649 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:26 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:26 DEBUG : : Looking for writers 2024/04/25 05:21:26 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetAll (2.70s) === RUN TestDirForgetPath run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:27 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:27 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:28.785706 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:28.785746 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:28 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:28 DEBUG : : Looking for writers 2024/04/25 05:21:28 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetPath (2.71s) === RUN TestDirWalk run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:30 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:30 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:31.481668 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:31.481706 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:31 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:31 DEBUG : : Looking for writers 2024/04/25 05:21:31 DEBUG : >WaitForWriters: --- FAIL: TestDirWalk (2.87s) === RUN TestDirSetModTime run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:33 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:33 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:34.349213 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:34.349237 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:34 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:34 DEBUG : : Looking for writers 2024/04/25 05:21:34 DEBUG : >WaitForWriters: --- FAIL: TestDirSetModTime (2.68s) === RUN TestDirStat run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:35 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:35 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:37.015297 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:37.015334 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:37 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:37 DEBUG : : Looking for writers 2024/04/25 05:21:37 DEBUG : >WaitForWriters: --- FAIL: TestDirStat (2.69s) === RUN TestDirReadDirAll run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:38 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:38 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:39.723431 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:39.723480 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:39 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:39 DEBUG : : Looking for writers 2024/04/25 05:21:39 DEBUG : >WaitForWriters: --- FAIL: TestDirReadDirAll (2.74s) === RUN TestDirOpen run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:41 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:41 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:42.564784 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:42.564823 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:42 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:42 DEBUG : : Looking for writers 2024/04/25 05:21:42 DEBUG : >WaitForWriters: --- FAIL: TestDirOpen (2.84s) === RUN TestDirCreate run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:43 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:43 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:45.313503 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:45.313538 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:45 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:45 DEBUG : : Looking for writers 2024/04/25 05:21:45 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (2.72s) === RUN TestDirMkdir run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:46 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:46 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:47.993250 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:47.993281 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:47 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:47 DEBUG : : Looking for writers 2024/04/25 05:21:47 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdir (2.72s) === RUN TestDirMkdirSub run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:49 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:49 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:50.720270 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:50.720303 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:50 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:50 DEBUG : : Looking for writers 2024/04/25 05:21:50 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdirSub (2.84s) === RUN TestDirRemove run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:52 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:52 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:53.646866 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:53.646902 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:53 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:53 DEBUG : : Looking for writers 2024/04/25 05:21:53 DEBUG : >WaitForWriters: --- FAIL: TestDirRemove (3.00s) === RUN TestDirRemoveAll run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:55 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:55 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:56.727000 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:56.727038 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:56 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:56 DEBUG : : Looking for writers 2024/04/25 05:21:56 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveAll (2.87s) === RUN TestDirRemoveName run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:21:58 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:21:58 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:59.502566 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:21:59.502604 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:21:59 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:59 DEBUG : : Looking for writers 2024/04/25 05:21:59 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (2.74s) === RUN TestDirRename run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:00 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:00 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:02.252936 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:02.252966 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:02 DEBUG : : Looking for writers 2024/04/25 05:22:02 DEBUG : >WaitForWriters: --- FAIL: TestDirRename (2.82s) === RUN TestFileMethods run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:03 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:03 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:05.060043 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:05.060075 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:05 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:05 DEBUG : : Looking for writers 2024/04/25 05:22:05 DEBUG : >WaitForWriters: --- FAIL: TestFileMethods (2.77s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:06 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:06 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:07.848570 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:07.848599 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:07 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:07 DEBUG : : Looking for writers 2024/04/25 05:22:07 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:09 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:09 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:10.884984 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:10.885049 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:10 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:10 DEBUG : : Looking for writers 2024/04/25 05:22:10 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:12 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:12 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:13.641248 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:13.641292 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:13 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:13 DEBUG : : Looking for writers 2024/04/25 05:22:13 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:14 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:14 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:22:14 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:14 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:14 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:14 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:14 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:22:16.211093 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:16.211134 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:16 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:16 DEBUG : : Looking for writers 2024/04/25 05:22:16 DEBUG : >WaitForWriters: 2024/04/25 05:22:16 DEBUG : vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:17 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:17 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:22:17 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:17 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:17 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:17 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:17 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:22:19.066823 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:19.066859 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:19 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:19 DEBUG : : Looking for writers 2024/04/25 05:22:19 DEBUG : >WaitForWriters: 2024/04/25 05:22:19 DEBUG : vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:20 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:20 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:22:20 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:20 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:20 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:20 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:20 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:22:21.982448 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:21.982481 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:21 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:21 DEBUG : : Looking for writers 2024/04/25 05:22:21 DEBUG : >WaitForWriters: 2024/04/25 05:22:21 DEBUG : vfs cache: cleaner exiting --- FAIL: TestFileSetModTime (17.01s) --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (2.99s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=false (2.82s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (2.71s) --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (2.62s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (2.93s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (2.94s) === RUN TestFileOpenRead run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:23 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:23 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:24.763173 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:24.763204 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:24 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:24 DEBUG : : Looking for writers 2024/04/25 05:22:24 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenRead (2.65s) === RUN TestFileOpenWrite run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:26 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:26 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:27.495878 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:27.495906 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:27 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:27 DEBUG : : Looking for writers 2024/04/25 05:22:27 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenWrite (2.85s) === RUN TestFileRemove run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:28 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:28 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:30.265468 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:30.265501 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:30 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:30 DEBUG : : Looking for writers 2024/04/25 05:22:30 DEBUG : >WaitForWriters: --- FAIL: TestFileRemove (2.66s) === RUN TestFileRemoveAll run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:31 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:31 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:33.012176 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:33.012207 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:33 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:33 DEBUG : : Looking for writers 2024/04/25 05:22:33 DEBUG : >WaitForWriters: --- FAIL: TestFileRemoveAll (2.98s) === RUN TestFileOpen run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:34 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:34 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:35.903140 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:35.903165 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:35 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:35 DEBUG : : Looking for writers 2024/04/25 05:22:35 DEBUG : >WaitForWriters: --- FAIL: TestFileOpen (2.72s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:37 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:37 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:38.764463 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:38.764493 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:38 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:38 DEBUG : : Looking for writers 2024/04/25 05:22:38 DEBUG : >WaitForWriters: === RUN TestFileRename/minimal,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:40 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:40 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:40 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:22:40 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:40 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:40 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:40 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:40 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:22:41.305455 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:41.305487 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:41 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:41 DEBUG : : Looking for writers 2024/04/25 05:22:41 DEBUG : >WaitForWriters: 2024/04/25 05:22:41 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/minimal,forceCache=true run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:42 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:42 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:42 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:22:42 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:42 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:42 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:42 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:42 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:22:44.234583 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:44.234609 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:44 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:44 DEBUG : : Looking for writers 2024/04/25 05:22:44 DEBUG : >WaitForWriters: 2024/04/25 05:22:44 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:45 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:45 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:22:45 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:45 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:45 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:45 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:45 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:22:47.041578 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:47.041616 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:47 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:47 DEBUG : : Looking for writers 2024/04/25 05:22:47 DEBUG : >WaitForWriters: 2024/04/25 05:22:47 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=true run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:48 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:48 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:22:48 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:48 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:48 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:48 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:48 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:22:49.942574 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:49.942613 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:49 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:49 DEBUG : : Looking for writers 2024/04/25 05:22:49 DEBUG : >WaitForWriters: 2024/04/25 05:22:49 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/full,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:51 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:51 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:22:51 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:51 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:51 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:51 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:22:51 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:22:52.720547 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:52.720597 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:52 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:52 DEBUG : : Looking for writers 2024/04/25 05:22:52 DEBUG : >WaitForWriters: 2024/04/25 05:22:52 DEBUG : vfs cache: cleaner exiting --- FAIL: TestFileRename (16.82s) --- FAIL: TestFileRename/off,forceCache=false (2.75s) --- FAIL: TestFileRename/minimal,forceCache=false (2.68s) --- FAIL: TestFileRename/minimal,forceCache=true (2.88s) --- FAIL: TestFileRename/writes,forceCache=false (2.86s) --- FAIL: TestFileRename/writes,forceCache=true (2.86s) --- FAIL: TestFileRename/full,forceCache=false (2.79s) === RUN TestReadFileHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:54 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:54 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:55.465662 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:55.465695 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:55 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:55 DEBUG : : Looking for writers 2024/04/25 05:22:55 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (2.76s) === RUN TestReadFileHandleSeek run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:56 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:56 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:22:58.226651 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:22:58.226674 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:22:58 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:22:58 DEBUG : : Looking for writers 2024/04/25 05:22:58 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleSeek (2.83s) === RUN TestReadFileHandleReadAt run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:22:59 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:22:59 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:23:00.982672 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:00.982710 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:00 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:00 DEBUG : : Looking for writers 2024/04/25 05:23:00 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (2.71s) === RUN TestReadFileHandleFlush run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:23:02 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:23:02 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:23:03.884831 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:03.884862 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:03 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:03 DEBUG : : Looking for writers 2024/04/25 05:23:03 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleFlush (2.88s) === RUN TestReadFileHandleRelease run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:23:05 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:23:05 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:23:06.704102 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:06.704138 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:06 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:06 DEBUG : : Looking for writers 2024/04/25 05:23:06 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (2.81s) === RUN TestRWFileHandleMethodsRead run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:23:08 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:23:08 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:23:08 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:08 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:08 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:08 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:08 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:23:09.447389 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:09.447429 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:09 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:09 DEBUG : : Looking for writers 2024/04/25 05:23:09 DEBUG : >WaitForWriters: 2024/04/25 05:23:09 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (2.80s) === RUN TestRWFileHandleSeek run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:23:10 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:23:10 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:23:10 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:10 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:10 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:10 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:10 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:23:12.359475 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:12.359510 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:12 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:12 DEBUG : : Looking for writers 2024/04/25 05:23:12 DEBUG : >WaitForWriters: 2024/04/25 05:23:12 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (2.85s) === RUN TestRWFileHandleReadAt run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:23:13 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:23:13 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:23:13 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:13 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:13 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:13 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:13 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:23:15.124779 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:15.124811 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:15 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:15 DEBUG : : Looking for writers 2024/04/25 05:23:15 DEBUG : >WaitForWriters: 2024/04/25 05:23:15 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (2.72s) === RUN TestRWFileHandleFlushRead run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:23:16 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:23:16 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:23:16 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:16 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:16 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:16 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:16 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:23:17.859224 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:17.859264 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:17 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:17 DEBUG : : Looking for writers 2024/04/25 05:23:17 DEBUG : >WaitForWriters: 2024/04/25 05:23:17 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleFlushRead (2.84s) === RUN TestRWFileHandleReleaseRead run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:23:19 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:23:19 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:23:19 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:19 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:19 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:19 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:19 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:23:20.780115 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:20.780162 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:20 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:20 DEBUG : : Looking for writers 2024/04/25 05:23:20 DEBUG : >WaitForWriters: 2024/04/25 05:23:20 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (2.99s) === RUN TestRWFileHandleMethodsWrite run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:23:22 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:23:22 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:23:22 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:22 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:22 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:22 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:23:22 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:23:22 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:23:22 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:23:22 DEBUG : file1: newRWFileHandle: 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): openPending: 2024/04/25 05:23:22 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:23:22 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): >openPending: err= 2024/04/25 05:23:22 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:23:22 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:23:22 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:23:22 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): _writeAt: size=5, off=0 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): >_writeAt: n=5, err= 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): _writeAt: size=7, off=5 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): >_writeAt: n=7, err= 2024/04/25 05:23:22 DEBUG : file1: vfs cache: truncate to size=11 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): close: 2024/04/25 05:23:22 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:23:22.651163661 +0000 UTC m=+130.999635649 2024/04/25 05:23:22 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): >close: err= 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): close: 2024/04/25 05:23:22 DEBUG : file1(0xc000bbd180): >close: err=file already closed 2024/04/25 05:23:22 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:23:22 DEBUG : : Looking for writers 2024/04/25 05:23:22 DEBUG : file1: reading active writers 2024/04/25 05:23:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:23:22 DEBUG : : Looking for writers 2024/04/25 05:23:22 DEBUG : file1: reading active writers 2024/04/25 05:23:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:23:22 DEBUG : : Looking for writers 2024/04/25 05:23:22 DEBUG : file1: reading active writers 2024/04/25 05:23:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:23:22 DEBUG : : Looking for writers 2024/04/25 05:23:22 DEBUG : file1: reading active writers 2024/04/25 05:23:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:23:22 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:23:22 DEBUG : : Looking for writers 2024/04/25 05:23:22 DEBUG : file1: reading active writers 2024/04/25 05:23:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:23:22 DEBUG : : Looking for writers 2024/04/25 05:23:22 DEBUG : file1: reading active writers 2024/04/25 05:23:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:23:23 DEBUG : : Looking for writers 2024/04/25 05:23:23 DEBUG : file1: reading active writers 2024/04/25 05:23:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:23:23.348582 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:23:23.348620 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:23 ERROR : file1: Failed to copy: 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:23 ERROR : file1: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:23:23 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:23:23 DEBUG : : Looking for writers 2024/04/25 05:23:23 DEBUG : file1: reading active writers 2024/04/25 05:23:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:24.293780 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:23:24.293817 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:23:24 DEBUG : : Looking for writers 2024/04/25 05:23:24 DEBUG : file1: reading active writers 2024/04/25 05:23:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:25 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:25 ERROR : file1: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:25 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:23:25 DEBUG : : Looking for writers 2024/04/25 05:23:25 DEBUG : file1: reading active writers 2024/04/25 05:23:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:26.486115 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:23:26.486152 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:23:26 DEBUG : : Looking for writers 2024/04/25 05:23:26 DEBUG : file1: reading active writers 2024/04/25 05:23:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:27 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:27 ERROR : file1: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:27 DEBUG : : Looking for writers 2024/04/25 05:23:27 DEBUG : file1: reading active writers 2024/04/25 05:23:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:28 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:23:28 DEBUG : : Looking for writers 2024/04/25 05:23:28 DEBUG : file1: reading active writers 2024/04/25 05:23:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:28.977784 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:23:28.977825 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:23:29 DEBUG : : Looking for writers 2024/04/25 05:23:29 DEBUG : file1: reading active writers 2024/04/25 05:23:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:30 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:30 ERROR : file1: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:30 DEBUG : : Looking for writers 2024/04/25 05:23:30 DEBUG : file1: reading active writers 2024/04/25 05:23:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:31 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:23:31 DEBUG : : Looking for writers 2024/04/25 05:23:31 DEBUG : file1: reading active writers 2024/04/25 05:23:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:32.333846 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:23:32.333870 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:23:32 DEBUG : : Looking for writers 2024/04/25 05:23:32 DEBUG : file1: reading active writers 2024/04/25 05:23:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:33 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:33 ERROR : file1: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:33 DEBUG : : Looking for writers 2024/04/25 05:23:33 DEBUG : file1: reading active writers 2024/04/25 05:23:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:34 DEBUG : : Looking for writers 2024/04/25 05:23:34 DEBUG : file1: reading active writers 2024/04/25 05:23:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:35 DEBUG : : Looking for writers 2024/04/25 05:23:35 DEBUG : file1: reading active writers 2024/04/25 05:23:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:36 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:23:36 DEBUG : : Looking for writers 2024/04/25 05:23:36 DEBUG : file1: reading active writers 2024/04/25 05:23:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:37.370699 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:23:37.370738 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:23:37 DEBUG : : Looking for writers 2024/04/25 05:23:37 DEBUG : file1: reading active writers 2024/04/25 05:23:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:38 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:38 ERROR : file1: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:38 DEBUG : : Looking for writers 2024/04/25 05:23:38 DEBUG : file1: reading active writers 2024/04/25 05:23:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:39 DEBUG : : Looking for writers 2024/04/25 05:23:39 DEBUG : file1: reading active writers 2024/04/25 05:23:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:40 DEBUG : : Looking for writers 2024/04/25 05:23:40 DEBUG : file1: reading active writers 2024/04/25 05:23:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:41 DEBUG : : Looking for writers 2024/04/25 05:23:41 DEBUG : file1: reading active writers 2024/04/25 05:23:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:42 DEBUG : : Looking for writers 2024/04/25 05:23:42 DEBUG : file1: reading active writers 2024/04/25 05:23:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:43 DEBUG : : Looking for writers 2024/04/25 05:23:43 DEBUG : file1: reading active writers 2024/04/25 05:23:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:44 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:23:44 DEBUG : : Looking for writers 2024/04/25 05:23:44 DEBUG : file1: reading active writers 2024/04/25 05:23:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:45.571237 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:23:45.571274 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:23:45 DEBUG : : Looking for writers 2024/04/25 05:23:45 DEBUG : file1: reading active writers 2024/04/25 05:23:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:46 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:46 ERROR : file1: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:23:46 DEBUG : : Looking for writers 2024/04/25 05:23:46 DEBUG : file1: reading active writers 2024/04/25 05:23:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:47 DEBUG : : Looking for writers 2024/04/25 05:23:47 DEBUG : file1: reading active writers 2024/04/25 05:23:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:48 DEBUG : : Looking for writers 2024/04/25 05:23:48 DEBUG : file1: reading active writers 2024/04/25 05:23:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:49 DEBUG : : Looking for writers 2024/04/25 05:23:49 DEBUG : file1: reading active writers 2024/04/25 05:23:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:50 DEBUG : : Looking for writers 2024/04/25 05:23:50 DEBUG : file1: reading active writers 2024/04/25 05:23:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:51 DEBUG : : Looking for writers 2024/04/25 05:23:51 DEBUG : file1: reading active writers 2024/04/25 05:23:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:23:52 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc0008dc1e0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000170a08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824635230784} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943874602167957517 ext:130999635649 loc:0x3a135e0} ATime:{wall:13943874602167982824 ext:130999660906 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:23:52 DEBUG : >WaitForWriters: fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/04/25 05:23:59 DEBUG : file1: vfs cache: starting upload fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 2024/04/25 05:24:08.378917 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:24:08.378948 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:24:09 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:24:09 ERROR : file1: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft fstest.go:295: Flushing the directory cache 2024/04/25 05:24:22 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:24:22 INFO : vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11) fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:337 Error: Should be true Test: TestRWFileHandleMethodsWrite Messages: listing wrong, want file1 (11) got fstest.go:197: Not found "file1" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:337 Error: Not equal: expected: 0 actual : 1 Test: TestRWFileHandleMethodsWrite Messages: 1 objects not found 2024/04/25 05:24:27 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:24:27 DEBUG : : Looking for writers 2024/04/25 05:24:27 DEBUG : file1: reading active writers 2024/04/25 05:24:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:24:27 DEBUG : : Looking for writers 2024/04/25 05:24:27 DEBUG : file1: reading active writers 2024/04/25 05:24:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:24:27 DEBUG : : Looking for writers 2024/04/25 05:24:27 DEBUG : file1: reading active writers 2024/04/25 05:24:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:24:28 DEBUG : : Looking for writers 2024/04/25 05:24:28 DEBUG : file1: reading active writers 2024/04/25 05:24:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:24:28 DEBUG : : Looking for writers 2024/04/25 05:24:28 DEBUG : file1: reading active writers 2024/04/25 05:24:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:24:28 DEBUG : : Looking for writers 2024/04/25 05:24:28 DEBUG : file1: reading active writers 2024/04/25 05:24:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:24:28 DEBUG : : Looking for writers 2024/04/25 05:24:28 DEBUG : file1: reading active writers 2024/04/25 05:24:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:24:29 DEBUG : : Looking for writers 2024/04/25 05:24:29 DEBUG : file1: reading active writers 2024/04/25 05:24:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:30 DEBUG : : Looking for writers 2024/04/25 05:24:30 DEBUG : file1: reading active writers 2024/04/25 05:24:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:31 DEBUG : : Looking for writers 2024/04/25 05:24:31 DEBUG : file1: reading active writers 2024/04/25 05:24:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:32 DEBUG : : Looking for writers 2024/04/25 05:24:32 DEBUG : file1: reading active writers 2024/04/25 05:24:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:33 DEBUG : : Looking for writers 2024/04/25 05:24:33 DEBUG : file1: reading active writers 2024/04/25 05:24:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:34 DEBUG : : Looking for writers 2024/04/25 05:24:34 DEBUG : file1: reading active writers 2024/04/25 05:24:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:35 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:24:35 DEBUG : : Looking for writers 2024/04/25 05:24:35 DEBUG : file1: reading active writers 2024/04/25 05:24:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:36 DEBUG : : Looking for writers 2024/04/25 05:24:36 DEBUG : file1: reading active writers 2024/04/25 05:24:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:37 DEBUG : : Looking for writers 2024/04/25 05:24:37 DEBUG : file1: reading active writers 2024/04/25 05:24:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:38 DEBUG : : Looking for writers 2024/04/25 05:24:38 DEBUG : file1: reading active writers 2024/04/25 05:24:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:39 DEBUG : : Looking for writers 2024/04/25 05:24:39 DEBUG : file1: reading active writers 2024/04/25 05:24:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:40 DEBUG : : Looking for writers 2024/04/25 05:24:40 DEBUG : file1: reading active writers 2024/04/25 05:24:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:41 DEBUG : : Looking for writers 2024/04/25 05:24:41 DEBUG : file1: reading active writers 2024/04/25 05:24:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:42 DEBUG : : Looking for writers 2024/04/25 05:24:42 DEBUG : file1: reading active writers 2024/04/25 05:24:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:43 DEBUG : : Looking for writers 2024/04/25 05:24:43 DEBUG : file1: reading active writers 2024/04/25 05:24:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:44 DEBUG : : Looking for writers 2024/04/25 05:24:44 DEBUG : file1: reading active writers 2024/04/25 05:24:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:45 DEBUG : : Looking for writers 2024/04/25 05:24:45 DEBUG : file1: reading active writers 2024/04/25 05:24:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:46 DEBUG : : Looking for writers 2024/04/25 05:24:46 DEBUG : file1: reading active writers 2024/04/25 05:24:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:47 DEBUG : : Looking for writers 2024/04/25 05:24:47 DEBUG : file1: reading active writers 2024/04/25 05:24:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:48 DEBUG : : Looking for writers 2024/04/25 05:24:48 DEBUG : file1: reading active writers 2024/04/25 05:24:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:49 DEBUG : : Looking for writers 2024/04/25 05:24:49 DEBUG : file1: reading active writers 2024/04/25 05:24:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:49.478780 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:24:49.478808 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:24:50 DEBUG : : Looking for writers 2024/04/25 05:24:50 DEBUG : file1: reading active writers 2024/04/25 05:24:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:50 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:24:50 ERROR : file1: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:24:51 DEBUG : : Looking for writers 2024/04/25 05:24:51 DEBUG : file1: reading active writers 2024/04/25 05:24:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:52 DEBUG : : Looking for writers 2024/04/25 05:24:52 DEBUG : file1: reading active writers 2024/04/25 05:24:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:53 DEBUG : : Looking for writers 2024/04/25 05:24:53 DEBUG : file1: reading active writers 2024/04/25 05:24:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:54 DEBUG : : Looking for writers 2024/04/25 05:24:54 DEBUG : file1: reading active writers 2024/04/25 05:24:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:55 DEBUG : : Looking for writers 2024/04/25 05:24:55 DEBUG : file1: reading active writers 2024/04/25 05:24:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:56 DEBUG : : Looking for writers 2024/04/25 05:24:56 DEBUG : file1: reading active writers 2024/04/25 05:24:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:57 DEBUG : : Looking for writers 2024/04/25 05:24:57 DEBUG : file1: reading active writers 2024/04/25 05:24:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:24:57 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc0008dc1e0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000170a08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824635230784} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943874602167957517 ext:130999635649 loc:0x3a135e0} ATime:{wall:13943874602167982824 ext:130999660906 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:24:57 DEBUG : >WaitForWriters: 2024/04/25 05:24:57 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsWrite (96.21s) === RUN TestRWFileHandleWriteAt run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:24:58 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:24:58 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:24:58 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:24:58 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:24:58 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:24:58 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:24:58 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:24:58 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:24:58 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:24:58 DEBUG : file1: newRWFileHandle: 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): openPending: 2024/04/25 05:24:58 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:24:58 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): >openPending: err= 2024/04/25 05:24:58 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:24:58 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:24:58 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:24:58 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): _writeAt: size=7, off=0 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): >_writeAt: n=7, err= 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): _writeAt: size=6, off=5 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): >_writeAt: n=6, err= 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): close: 2024/04/25 05:24:58 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:24:58.887563763 +0000 UTC m=+227.236035721 2024/04/25 05:24:58 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): >close: err= 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): _writeAt: size=5, off=0 2024/04/25 05:24:58 DEBUG : file1(0xc000c92a40): >_writeAt: n=0, err=file already closed 2024/04/25 05:24:58 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:24:58 DEBUG : : Looking for writers 2024/04/25 05:24:58 DEBUG : file1: reading active writers 2024/04/25 05:24:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:24:58 DEBUG : : Looking for writers 2024/04/25 05:24:58 DEBUG : file1: reading active writers 2024/04/25 05:24:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:24:58 DEBUG : : Looking for writers 2024/04/25 05:24:58 DEBUG : file1: reading active writers 2024/04/25 05:24:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:24:58 DEBUG : : Looking for writers 2024/04/25 05:24:58 DEBUG : file1: reading active writers 2024/04/25 05:24:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:24:58 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:24:59 DEBUG : : Looking for writers 2024/04/25 05:24:59 DEBUG : file1: reading active writers 2024/04/25 05:24:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:24:59 DEBUG : : Looking for writers 2024/04/25 05:24:59 DEBUG : file1: reading active writers 2024/04/25 05:24:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:24:59 DEBUG : : Looking for writers 2024/04/25 05:24:59 DEBUG : file1: reading active writers 2024/04/25 05:24:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:24:59.712250 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:24:59.712283 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:25:00 DEBUG : : Looking for writers 2024/04/25 05:25:00 DEBUG : file1: reading active writers 2024/04/25 05:25:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:00 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:00 ERROR : file1: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:00 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:25:01 DEBUG : : Looking for writers 2024/04/25 05:25:01 DEBUG : file1: reading active writers 2024/04/25 05:25:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:01.672621 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:25:01.672650 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:25:02 DEBUG : : Looking for writers 2024/04/25 05:25:02 DEBUG : file1: reading active writers 2024/04/25 05:25:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:02 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:02 ERROR : file1: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:03 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:25:03 DEBUG : : Looking for writers 2024/04/25 05:25:03 DEBUG : file1: reading active writers 2024/04/25 05:25:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:03.866913 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:25:03.866944 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:25:04 DEBUG : : Looking for writers 2024/04/25 05:25:04 DEBUG : file1: reading active writers 2024/04/25 05:25:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:04 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:04 ERROR : file1: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:05 DEBUG : : Looking for writers 2024/04/25 05:25:05 DEBUG : file1: reading active writers 2024/04/25 05:25:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:05 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:25:06 DEBUG : : Looking for writers 2024/04/25 05:25:06 DEBUG : file1: reading active writers 2024/04/25 05:25:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:06.474879 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:25:06.474924 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:25:07 DEBUG : : Looking for writers 2024/04/25 05:25:07 DEBUG : file1: reading active writers 2024/04/25 05:25:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:07 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:07 ERROR : file1: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:08 DEBUG : : Looking for writers 2024/04/25 05:25:08 DEBUG : file1: reading active writers 2024/04/25 05:25:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:09 DEBUG : : Looking for writers 2024/04/25 05:25:09 DEBUG : file1: reading active writers 2024/04/25 05:25:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:09 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:25:09.818567 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:25:09.818602 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:25:10 DEBUG : : Looking for writers 2024/04/25 05:25:10 DEBUG : file1: reading active writers 2024/04/25 05:25:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:10 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:10 ERROR : file1: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:11 DEBUG : : Looking for writers 2024/04/25 05:25:11 DEBUG : file1: reading active writers 2024/04/25 05:25:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:12 DEBUG : : Looking for writers 2024/04/25 05:25:12 DEBUG : file1: reading active writers 2024/04/25 05:25:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:13 DEBUG : : Looking for writers 2024/04/25 05:25:13 DEBUG : file1: reading active writers 2024/04/25 05:25:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:14 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:25:14 DEBUG : : Looking for writers 2024/04/25 05:25:14 DEBUG : file1: reading active writers 2024/04/25 05:25:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:14.758185 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:25:14.758223 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:25:15 DEBUG : : Looking for writers 2024/04/25 05:25:15 DEBUG : file1: reading active writers 2024/04/25 05:25:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:15 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:15 ERROR : file1: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:16 DEBUG : : Looking for writers 2024/04/25 05:25:16 DEBUG : file1: reading active writers 2024/04/25 05:25:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:17 DEBUG : : Looking for writers 2024/04/25 05:25:17 DEBUG : file1: reading active writers 2024/04/25 05:25:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:18 DEBUG : : Looking for writers 2024/04/25 05:25:18 DEBUG : file1: reading active writers 2024/04/25 05:25:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:19 DEBUG : : Looking for writers 2024/04/25 05:25:19 DEBUG : file1: reading active writers 2024/04/25 05:25:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:20 DEBUG : : Looking for writers 2024/04/25 05:25:20 DEBUG : file1: reading active writers 2024/04/25 05:25:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:21 DEBUG : : Looking for writers 2024/04/25 05:25:21 DEBUG : file1: reading active writers 2024/04/25 05:25:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:22 DEBUG : : Looking for writers 2024/04/25 05:25:22 DEBUG : file1: reading active writers 2024/04/25 05:25:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:22 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:25:22.976841 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:25:22.976872 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:25:23 DEBUG : : Looking for writers 2024/04/25 05:25:23 DEBUG : file1: reading active writers 2024/04/25 05:25:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:24 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:24 ERROR : file1: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:24 DEBUG : : Looking for writers 2024/04/25 05:25:24 DEBUG : file1: reading active writers 2024/04/25 05:25:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:25 DEBUG : : Looking for writers 2024/04/25 05:25:25 DEBUG : file1: reading active writers 2024/04/25 05:25:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:26 DEBUG : : Looking for writers 2024/04/25 05:25:26 DEBUG : file1: reading active writers 2024/04/25 05:25:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:27 DEBUG : : Looking for writers 2024/04/25 05:25:27 DEBUG : file1: reading active writers 2024/04/25 05:25:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:28 DEBUG : : Looking for writers 2024/04/25 05:25:28 DEBUG : file1: reading active writers 2024/04/25 05:25:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:25:28 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc0007965a0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0005ded08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824639876416} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943874705483572723 ext:227236035721 loc:0x3a135e0} ATime:{wall:13943874705483601397 ext:227236064404 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:25:28 DEBUG : >WaitForWriters: fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/04/25 05:25:36 DEBUG : file1: vfs cache: starting upload fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 2024/04/25 05:25:44.950999 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:25:44.951032 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:25:45 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:25:45 ERROR : file1: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft fstest.go:295: Flushing the directory cache 2024/04/25 05:25:58 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:25:58 INFO : vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11) fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:387 Error: Should be true Test: TestRWFileHandleWriteAt Messages: listing wrong, want file1 (11) got fstest.go:197: Not found "file1" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:387 Error: Not equal: expected: 0 actual : 1 Test: TestRWFileHandleWriteAt Messages: 1 objects not found 2024/04/25 05:26:04 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:26:04 DEBUG : : Looking for writers 2024/04/25 05:26:04 DEBUG : file1: reading active writers 2024/04/25 05:26:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:26:04 DEBUG : : Looking for writers 2024/04/25 05:26:04 DEBUG : file1: reading active writers 2024/04/25 05:26:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:26:04 DEBUG : : Looking for writers 2024/04/25 05:26:04 DEBUG : file1: reading active writers 2024/04/25 05:26:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:26:04 DEBUG : : Looking for writers 2024/04/25 05:26:04 DEBUG : file1: reading active writers 2024/04/25 05:26:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:26:04 DEBUG : : Looking for writers 2024/04/25 05:26:04 DEBUG : file1: reading active writers 2024/04/25 05:26:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:26:05 DEBUG : : Looking for writers 2024/04/25 05:26:05 DEBUG : file1: reading active writers 2024/04/25 05:26:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:26:05 DEBUG : : Looking for writers 2024/04/25 05:26:05 DEBUG : file1: reading active writers 2024/04/25 05:26:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:26:06 DEBUG : : Looking for writers 2024/04/25 05:26:06 DEBUG : file1: reading active writers 2024/04/25 05:26:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:07 DEBUG : : Looking for writers 2024/04/25 05:26:07 DEBUG : file1: reading active writers 2024/04/25 05:26:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:08 DEBUG : : Looking for writers 2024/04/25 05:26:08 DEBUG : file1: reading active writers 2024/04/25 05:26:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:09 DEBUG : : Looking for writers 2024/04/25 05:26:09 DEBUG : file1: reading active writers 2024/04/25 05:26:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:10 DEBUG : : Looking for writers 2024/04/25 05:26:10 DEBUG : file1: reading active writers 2024/04/25 05:26:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:11 DEBUG : : Looking for writers 2024/04/25 05:26:11 DEBUG : file1: reading active writers 2024/04/25 05:26:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:11 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:26:12 DEBUG : : Looking for writers 2024/04/25 05:26:12 DEBUG : file1: reading active writers 2024/04/25 05:26:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:13 DEBUG : : Looking for writers 2024/04/25 05:26:13 DEBUG : file1: reading active writers 2024/04/25 05:26:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:14 DEBUG : : Looking for writers 2024/04/25 05:26:14 DEBUG : file1: reading active writers 2024/04/25 05:26:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:15 DEBUG : : Looking for writers 2024/04/25 05:26:15 DEBUG : file1: reading active writers 2024/04/25 05:26:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:16 DEBUG : : Looking for writers 2024/04/25 05:26:16 DEBUG : file1: reading active writers 2024/04/25 05:26:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:17 DEBUG : : Looking for writers 2024/04/25 05:26:17 DEBUG : file1: reading active writers 2024/04/25 05:26:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:18 DEBUG : : Looking for writers 2024/04/25 05:26:18 DEBUG : file1: reading active writers 2024/04/25 05:26:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:19 DEBUG : : Looking for writers 2024/04/25 05:26:19 DEBUG : file1: reading active writers 2024/04/25 05:26:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:20 DEBUG : : Looking for writers 2024/04/25 05:26:20 DEBUG : file1: reading active writers 2024/04/25 05:26:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:21 DEBUG : : Looking for writers 2024/04/25 05:26:21 DEBUG : file1: reading active writers 2024/04/25 05:26:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:22 DEBUG : : Looking for writers 2024/04/25 05:26:22 DEBUG : file1: reading active writers 2024/04/25 05:26:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:23 DEBUG : : Looking for writers 2024/04/25 05:26:23 DEBUG : file1: reading active writers 2024/04/25 05:26:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:24 DEBUG : : Looking for writers 2024/04/25 05:26:24 DEBUG : file1: reading active writers 2024/04/25 05:26:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:25 DEBUG : : Looking for writers 2024/04/25 05:26:25 DEBUG : file1: reading active writers 2024/04/25 05:26:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:26 DEBUG : : Looking for writers 2024/04/25 05:26:26 DEBUG : file1: reading active writers 2024/04/25 05:26:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:26.315107 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:26:26.315140 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:26:27 DEBUG : : Looking for writers 2024/04/25 05:26:27 DEBUG : file1: reading active writers 2024/04/25 05:26:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:27 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:27 ERROR : file1: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:28 DEBUG : : Looking for writers 2024/04/25 05:26:28 DEBUG : file1: reading active writers 2024/04/25 05:26:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:29 DEBUG : : Looking for writers 2024/04/25 05:26:29 DEBUG : file1: reading active writers 2024/04/25 05:26:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:30 DEBUG : : Looking for writers 2024/04/25 05:26:30 DEBUG : file1: reading active writers 2024/04/25 05:26:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:31 DEBUG : : Looking for writers 2024/04/25 05:26:31 DEBUG : file1: reading active writers 2024/04/25 05:26:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:32 DEBUG : : Looking for writers 2024/04/25 05:26:32 DEBUG : file1: reading active writers 2024/04/25 05:26:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:33 DEBUG : : Looking for writers 2024/04/25 05:26:33 DEBUG : file1: reading active writers 2024/04/25 05:26:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:34 DEBUG : : Looking for writers 2024/04/25 05:26:34 DEBUG : file1: reading active writers 2024/04/25 05:26:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:34 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc0007965a0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0005ded08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824639876416} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943874705483572723 ext:227236035721 loc:0x3a135e0} ATime:{wall:13943874705483601397 ext:227236064404 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:26:34 DEBUG : >WaitForWriters: 2024/04/25 05:26:34 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteAt (96.82s) === RUN TestRWFileHandleWriteNoWrite run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:26:35 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:26:35 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:26:35 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:26:35 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:26:35 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:26:35 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:26:35 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:26:35 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:26:35 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:26:35 DEBUG : file1: newRWFileHandle: 2024/04/25 05:26:35 DEBUG : file1(0xc000c92600): openPending: 2024/04/25 05:26:35 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:26:35 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:26:35 DEBUG : file1(0xc000c92600): >openPending: err= 2024/04/25 05:26:35 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:26:35 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:26:35 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:26:35 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:26:35 DEBUG : file1(0xc000c92600): close: 2024/04/25 05:26:35 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:26:35.639623179 +0000 UTC m=+323.988095147 2024/04/25 05:26:35 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:26:35 DEBUG : file1(0xc000c92600): >close: err= 2024/04/25 05:26:35 DEBUG : file2: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2024/04/25 05:26:35 DEBUG : file2: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2024/04/25 05:26:35 DEBUG : file2: newRWFileHandle: 2024/04/25 05:26:35 DEBUG : file2(0xc000c92780): openPending: 2024/04/25 05:26:35 DEBUG : file2: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:26:35 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:26:35 DEBUG : file2(0xc000c92780): >openPending: err= 2024/04/25 05:26:35 DEBUG : file2: >newRWFileHandle: err= 2024/04/25 05:26:35 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:26:35 DEBUG : file2: >Open: fd=file2 (rw), err= 2024/04/25 05:26:35 DEBUG : file2: >OpenFile: fd=file2 (rw), err= 2024/04/25 05:26:35 DEBUG : file2(0xc000c92780): RWFileHandle.Flush 2024/04/25 05:26:35 DEBUG : file2(0xc000c92780): RWFileHandle.Release 2024/04/25 05:26:35 DEBUG : file2(0xc000c92780): close: 2024/04/25 05:26:35 DEBUG : file2: vfs cache: setting modification time to 2024-04-25 05:26:35.640943614 +0000 UTC m=+323.989415572 2024/04/25 05:26:35 INFO : file2: vfs cache: queuing for upload in 100ms 2024/04/25 05:26:35 DEBUG : file2(0xc000c92780): >close: err= 2024/04/25 05:26:35 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:26:35 DEBUG : : Looking for writers 2024/04/25 05:26:35 DEBUG : file1: reading active writers 2024/04/25 05:26:35 DEBUG : file2: reading active writers 2024/04/25 05:26:35 DEBUG : Still 0 writers active and 2 cache items in use, waiting 10ms 2024/04/25 05:26:35 DEBUG : : Looking for writers 2024/04/25 05:26:35 DEBUG : file1: reading active writers 2024/04/25 05:26:35 DEBUG : file2: reading active writers 2024/04/25 05:26:35 DEBUG : Still 0 writers active and 2 cache items in use, waiting 20ms 2024/04/25 05:26:35 DEBUG : : Looking for writers 2024/04/25 05:26:35 DEBUG : file1: reading active writers 2024/04/25 05:26:35 DEBUG : file2: reading active writers 2024/04/25 05:26:35 DEBUG : Still 0 writers active and 2 cache items in use, waiting 40ms 2024/04/25 05:26:35 DEBUG : : Looking for writers 2024/04/25 05:26:35 DEBUG : file1: reading active writers 2024/04/25 05:26:35 DEBUG : file2: reading active writers 2024/04/25 05:26:35 DEBUG : Still 0 writers active and 2 cache items in use, waiting 80ms 2024/04/25 05:26:35 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:26:35 DEBUG : file2: vfs cache: starting upload 2024/04/25 05:26:35 DEBUG : : Looking for writers 2024/04/25 05:26:35 DEBUG : file2: reading active writers 2024/04/25 05:26:35 DEBUG : file1: reading active writers 2024/04/25 05:26:35 DEBUG : Still 0 writers active and 2 cache items in use, waiting 160ms 2024/04/25 05:26:35 DEBUG : : Looking for writers 2024/04/25 05:26:35 DEBUG : file1: reading active writers 2024/04/25 05:26:35 DEBUG : file2: reading active writers 2024/04/25 05:26:35 DEBUG : Still 0 writers active and 2 cache items in use, waiting 320ms 2024/04/25 05:26:36 DEBUG : : Looking for writers 2024/04/25 05:26:36 DEBUG : file1: reading active writers 2024/04/25 05:26:36 DEBUG : file2: reading active writers 2024/04/25 05:26:36 DEBUG : Still 0 writers active and 2 cache items in use, waiting 640ms 2024/04/25 05:26:36.423405 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:26:36.423440 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:26:36 DEBUG : file2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/04/25 05:26:36 INFO : file2: Copied (new) 2024/04/25 05:26:36 DEBUG : file2: vfs cache: fingerprint now "0,2024-04-25 05:26:35.640943614 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709" 2024/04/25 05:26:36 DEBUG : file2: vfs cache: writeback object to VFS layer 2024/04/25 05:26:36 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:26:36 INFO : file2: vfs cache: upload succeeded try #1 2024/04/25 05:26:36 DEBUG : : Looking for writers 2024/04/25 05:26:36 DEBUG : file1: reading active writers 2024/04/25 05:26:36 DEBUG : file2: reading active writers 2024/04/25 05:26:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:37 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:37 ERROR : file1: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:37 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:26:37 DEBUG : : Looking for writers 2024/04/25 05:26:37 DEBUG : file1: reading active writers 2024/04/25 05:26:37 DEBUG : file2: reading active writers 2024/04/25 05:26:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:38.410571 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:26:38.410611 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:26:38 DEBUG : : Looking for writers 2024/04/25 05:26:38 DEBUG : file1: reading active writers 2024/04/25 05:26:38 DEBUG : file2: reading active writers 2024/04/25 05:26:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:39 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:39 ERROR : file1: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:39 DEBUG : : Looking for writers 2024/04/25 05:26:39 DEBUG : file1: reading active writers 2024/04/25 05:26:39 DEBUG : file2: reading active writers 2024/04/25 05:26:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:39 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:26:40.620022 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:26:40.620052 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:26:40 DEBUG : : Looking for writers 2024/04/25 05:26:40 DEBUG : file1: reading active writers 2024/04/25 05:26:40 DEBUG : file2: reading active writers 2024/04/25 05:26:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:41 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:41 ERROR : file1: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:41 DEBUG : : Looking for writers 2024/04/25 05:26:41 DEBUG : file1: reading active writers 2024/04/25 05:26:41 DEBUG : file2: reading active writers 2024/04/25 05:26:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:42 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:26:42 DEBUG : : Looking for writers 2024/04/25 05:26:42 DEBUG : file1: reading active writers 2024/04/25 05:26:42 DEBUG : file2: reading active writers 2024/04/25 05:26:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:43.172176 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:26:43.172217 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:26:43 DEBUG : : Looking for writers 2024/04/25 05:26:43 DEBUG : file1: reading active writers 2024/04/25 05:26:43 DEBUG : file2: reading active writers 2024/04/25 05:26:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:44 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:44 ERROR : file1: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:44 DEBUG : : Looking for writers 2024/04/25 05:26:44 DEBUG : file1: reading active writers 2024/04/25 05:26:44 DEBUG : file2: reading active writers 2024/04/25 05:26:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:45 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:26:45 DEBUG : : Looking for writers 2024/04/25 05:26:45 DEBUG : file1: reading active writers 2024/04/25 05:26:45 DEBUG : file2: reading active writers 2024/04/25 05:26:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:46.538828 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:26:46.538858 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:26:46 DEBUG : : Looking for writers 2024/04/25 05:26:46 DEBUG : file1: reading active writers 2024/04/25 05:26:46 DEBUG : file2: reading active writers 2024/04/25 05:26:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:47 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:47 ERROR : file1: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:47 DEBUG : : Looking for writers 2024/04/25 05:26:47 DEBUG : file1: reading active writers 2024/04/25 05:26:47 DEBUG : file2: reading active writers 2024/04/25 05:26:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:48 DEBUG : : Looking for writers 2024/04/25 05:26:48 DEBUG : file2: reading active writers 2024/04/25 05:26:48 DEBUG : file1: reading active writers 2024/04/25 05:26:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:49 DEBUG : : Looking for writers 2024/04/25 05:26:49 DEBUG : file2: reading active writers 2024/04/25 05:26:49 DEBUG : file1: reading active writers 2024/04/25 05:26:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:50 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:26:50 DEBUG : : Looking for writers 2024/04/25 05:26:50 DEBUG : file1: reading active writers 2024/04/25 05:26:50 DEBUG : file2: reading active writers 2024/04/25 05:26:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:51.565182 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:26:51.565202 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:26:51 DEBUG : : Looking for writers 2024/04/25 05:26:51 DEBUG : file1: reading active writers 2024/04/25 05:26:51 DEBUG : file2: reading active writers 2024/04/25 05:26:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:52 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:52 ERROR : file1: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:26:52 DEBUG : : Looking for writers 2024/04/25 05:26:52 DEBUG : file1: reading active writers 2024/04/25 05:26:52 DEBUG : file2: reading active writers 2024/04/25 05:26:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:53 DEBUG : : Looking for writers 2024/04/25 05:26:53 DEBUG : file1: reading active writers 2024/04/25 05:26:53 DEBUG : file2: reading active writers 2024/04/25 05:26:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:54 DEBUG : : Looking for writers 2024/04/25 05:26:54 DEBUG : file2: reading active writers 2024/04/25 05:26:54 DEBUG : file1: reading active writers 2024/04/25 05:26:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:55 DEBUG : : Looking for writers 2024/04/25 05:26:55 DEBUG : file1: reading active writers 2024/04/25 05:26:55 DEBUG : file2: reading active writers 2024/04/25 05:26:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:56 DEBUG : : Looking for writers 2024/04/25 05:26:56 DEBUG : file1: reading active writers 2024/04/25 05:26:56 DEBUG : file2: reading active writers 2024/04/25 05:26:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:57 DEBUG : : Looking for writers 2024/04/25 05:26:57 DEBUG : file1: reading active writers 2024/04/25 05:26:57 DEBUG : file2: reading active writers 2024/04/25 05:26:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:58 DEBUG : : Looking for writers 2024/04/25 05:26:58 DEBUG : file1: reading active writers 2024/04/25 05:26:58 DEBUG : file2: reading active writers 2024/04/25 05:26:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:26:59 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:26:59.749718 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:26:59.749753 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:26:59 DEBUG : : Looking for writers 2024/04/25 05:26:59 DEBUG : file1: reading active writers 2024/04/25 05:26:59 DEBUG : file2: reading active writers 2024/04/25 05:26:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:00 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:27:00 ERROR : file1: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:27:00 DEBUG : : Looking for writers 2024/04/25 05:27:00 DEBUG : file1: reading active writers 2024/04/25 05:27:00 DEBUG : file2: reading active writers 2024/04/25 05:27:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:01 DEBUG : : Looking for writers 2024/04/25 05:27:01 DEBUG : file1: reading active writers 2024/04/25 05:27:01 DEBUG : file2: reading active writers 2024/04/25 05:27:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:02 DEBUG : : Looking for writers 2024/04/25 05:27:02 DEBUG : file1: reading active writers 2024/04/25 05:27:02 DEBUG : file2: reading active writers 2024/04/25 05:27:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:03 DEBUG : : Looking for writers 2024/04/25 05:27:03 DEBUG : file2: reading active writers 2024/04/25 05:27:03 DEBUG : file1: reading active writers 2024/04/25 05:27:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:04 DEBUG : : Looking for writers 2024/04/25 05:27:04 DEBUG : file1: reading active writers 2024/04/25 05:27:04 DEBUG : file2: reading active writers 2024/04/25 05:27:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:05 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc0008dc3c0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0007a6608 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641742400} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943874809388589067 ext:323988095147 loc:0x3a135e0} ATime:{wall:13943874809388908296 ext:323988414376 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, "file2": &{c:0xc0008dc3c0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0007a6708 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641742656} name:file2 opens:0 downloaders: o:0xc000bc0320 fd: info:{ModTime:{wall:13943874809389909502 ext:323989415572 loc:0x3a135e0} ATime:{wall:13943874809390132170 ext:323989638240 loc:0x3a135e0} Size:0 Rs:[] Fingerprint:0,2024-04-25 05:26:35.640943614 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709 Dirty:false} writeBackID:2 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:27:05 DEBUG : >WaitForWriters: fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/04/25 05:27:13 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:27:22.173216 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:27:22.173242 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 2024/04/25 05:27:23 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:27:23 ERROR : file1: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft fstest.go:295: Flushing the directory cache 2024/04/25 05:27:35 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:27:35 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file2 not removed, freed 0 bytes 2024/04/25 05:27:35 INFO : vfs cache: cleaned: objects 2 (was 2) in use 1, to upload 1, uploading 0, total size 0 (was 0) fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:420 Error: Should be true Test: TestRWFileHandleWriteNoWrite Messages: listing wrong, want file1 (0), file2 (0) got file2 (0) fstest.go:197: Not found "file1" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:420 Error: Not equal: expected: 0 actual : 1 Test: TestRWFileHandleWriteNoWrite Messages: 1 objects not found 2024/04/25 05:27:42 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:27:42 DEBUG : : Looking for writers 2024/04/25 05:27:42 DEBUG : file1: reading active writers 2024/04/25 05:27:42 DEBUG : file2: reading active writers 2024/04/25 05:27:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:27:42 DEBUG : : Looking for writers 2024/04/25 05:27:42 DEBUG : file1: reading active writers 2024/04/25 05:27:42 DEBUG : file2: reading active writers 2024/04/25 05:27:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:27:42 DEBUG : : Looking for writers 2024/04/25 05:27:42 DEBUG : file1: reading active writers 2024/04/25 05:27:42 DEBUG : file2: reading active writers 2024/04/25 05:27:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:27:43 DEBUG : : Looking for writers 2024/04/25 05:27:43 DEBUG : file1: reading active writers 2024/04/25 05:27:43 DEBUG : file2: reading active writers 2024/04/25 05:27:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:27:43 DEBUG : : Looking for writers 2024/04/25 05:27:43 DEBUG : file2: reading active writers 2024/04/25 05:27:43 DEBUG : file1: reading active writers 2024/04/25 05:27:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:27:43 DEBUG : : Looking for writers 2024/04/25 05:27:43 DEBUG : file2: reading active writers 2024/04/25 05:27:43 DEBUG : file1: reading active writers 2024/04/25 05:27:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:27:43 DEBUG : : Looking for writers 2024/04/25 05:27:43 DEBUG : file1: reading active writers 2024/04/25 05:27:43 DEBUG : file2: reading active writers 2024/04/25 05:27:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:27:44 DEBUG : : Looking for writers 2024/04/25 05:27:44 DEBUG : file1: reading active writers 2024/04/25 05:27:44 DEBUG : file2: reading active writers 2024/04/25 05:27:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:45 DEBUG : : Looking for writers 2024/04/25 05:27:45 DEBUG : file1: reading active writers 2024/04/25 05:27:45 DEBUG : file2: reading active writers 2024/04/25 05:27:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:46 DEBUG : : Looking for writers 2024/04/25 05:27:46 DEBUG : file1: reading active writers 2024/04/25 05:27:46 DEBUG : file2: reading active writers 2024/04/25 05:27:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:47 DEBUG : : Looking for writers 2024/04/25 05:27:47 DEBUG : file1: reading active writers 2024/04/25 05:27:47 DEBUG : file2: reading active writers 2024/04/25 05:27:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:48 DEBUG : : Looking for writers 2024/04/25 05:27:48 DEBUG : file1: reading active writers 2024/04/25 05:27:48 DEBUG : file2: reading active writers 2024/04/25 05:27:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:48 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:27:49 DEBUG : : Looking for writers 2024/04/25 05:27:49 DEBUG : file1: reading active writers 2024/04/25 05:27:49 DEBUG : file2: reading active writers 2024/04/25 05:27:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:50 DEBUG : : Looking for writers 2024/04/25 05:27:50 DEBUG : file1: reading active writers 2024/04/25 05:27:50 DEBUG : file2: reading active writers 2024/04/25 05:27:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:51 DEBUG : : Looking for writers 2024/04/25 05:27:51 DEBUG : file1: reading active writers 2024/04/25 05:27:51 DEBUG : file2: reading active writers 2024/04/25 05:27:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:52 DEBUG : : Looking for writers 2024/04/25 05:27:52 DEBUG : file1: reading active writers 2024/04/25 05:27:52 DEBUG : file2: reading active writers 2024/04/25 05:27:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:53 DEBUG : : Looking for writers 2024/04/25 05:27:53 DEBUG : file1: reading active writers 2024/04/25 05:27:53 DEBUG : file2: reading active writers 2024/04/25 05:27:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:54 DEBUG : : Looking for writers 2024/04/25 05:27:54 DEBUG : file1: reading active writers 2024/04/25 05:27:54 DEBUG : file2: reading active writers 2024/04/25 05:27:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:55 DEBUG : : Looking for writers 2024/04/25 05:27:55 DEBUG : file1: reading active writers 2024/04/25 05:27:55 DEBUG : file2: reading active writers 2024/04/25 05:27:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:56 DEBUG : : Looking for writers 2024/04/25 05:27:56 DEBUG : file1: reading active writers 2024/04/25 05:27:56 DEBUG : file2: reading active writers 2024/04/25 05:27:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:57 DEBUG : : Looking for writers 2024/04/25 05:27:57 DEBUG : file2: reading active writers 2024/04/25 05:27:57 DEBUG : file1: reading active writers 2024/04/25 05:27:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:58 DEBUG : : Looking for writers 2024/04/25 05:27:58 DEBUG : file1: reading active writers 2024/04/25 05:27:58 DEBUG : file2: reading active writers 2024/04/25 05:27:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:27:59 DEBUG : : Looking for writers 2024/04/25 05:27:59 DEBUG : file1: reading active writers 2024/04/25 05:27:59 DEBUG : file2: reading active writers 2024/04/25 05:27:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:00 DEBUG : : Looking for writers 2024/04/25 05:28:00 DEBUG : file1: reading active writers 2024/04/25 05:28:00 DEBUG : file2: reading active writers 2024/04/25 05:28:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:01 DEBUG : : Looking for writers 2024/04/25 05:28:01 DEBUG : file1: reading active writers 2024/04/25 05:28:01 DEBUG : file2: reading active writers 2024/04/25 05:28:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:02 DEBUG : : Looking for writers 2024/04/25 05:28:02 DEBUG : file2: reading active writers 2024/04/25 05:28:02 DEBUG : file1: reading active writers 2024/04/25 05:28:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:03 DEBUG : : Looking for writers 2024/04/25 05:28:03 DEBUG : file1: reading active writers 2024/04/25 05:28:03 DEBUG : file2: reading active writers 2024/04/25 05:28:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:04.138982 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:28:04.139009 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:28:04 DEBUG : : Looking for writers 2024/04/25 05:28:04 DEBUG : file1: reading active writers 2024/04/25 05:28:04 DEBUG : file2: reading active writers 2024/04/25 05:28:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:05 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:28:05 ERROR : file1: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:28:05 DEBUG : : Looking for writers 2024/04/25 05:28:05 DEBUG : file1: reading active writers 2024/04/25 05:28:05 DEBUG : file2: reading active writers 2024/04/25 05:28:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:06 DEBUG : : Looking for writers 2024/04/25 05:28:06 DEBUG : file1: reading active writers 2024/04/25 05:28:06 DEBUG : file2: reading active writers 2024/04/25 05:28:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:07 DEBUG : : Looking for writers 2024/04/25 05:28:07 DEBUG : file1: reading active writers 2024/04/25 05:28:07 DEBUG : file2: reading active writers 2024/04/25 05:28:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:08 DEBUG : : Looking for writers 2024/04/25 05:28:08 DEBUG : file2: reading active writers 2024/04/25 05:28:08 DEBUG : file1: reading active writers 2024/04/25 05:28:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:09 DEBUG : : Looking for writers 2024/04/25 05:28:09 DEBUG : file1: reading active writers 2024/04/25 05:28:09 DEBUG : file2: reading active writers 2024/04/25 05:28:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:10 DEBUG : : Looking for writers 2024/04/25 05:28:10 DEBUG : file1: reading active writers 2024/04/25 05:28:10 DEBUG : file2: reading active writers 2024/04/25 05:28:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:11 DEBUG : : Looking for writers 2024/04/25 05:28:11 DEBUG : file1: reading active writers 2024/04/25 05:28:11 DEBUG : file2: reading active writers 2024/04/25 05:28:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:12 DEBUG : : Looking for writers 2024/04/25 05:28:12 DEBUG : file1: reading active writers 2024/04/25 05:28:12 DEBUG : file2: reading active writers 2024/04/25 05:28:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:12 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc0008dc3c0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0007a6608 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641742400} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943874809388589067 ext:323988095147 loc:0x3a135e0} ATime:{wall:13943874809388908296 ext:323988414376 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, "file2": &{c:0xc0008dc3c0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0007a6708 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641742656} name:file2 opens:0 downloaders: o:0xc000bc0320 fd: info:{ModTime:{wall:13943874809389909502 ext:323989415572 loc:0x3a135e0} ATime:{wall:13943874809390132170 ext:323989638240 loc:0x3a135e0} Size:0 Rs:[] Fingerprint:0,2024-04-25 05:26:35.640943614 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709 Dirty:false} writeBackID:2 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:28:12 DEBUG : >WaitForWriters: 2024/04/25 05:28:12 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteNoWrite (99.31s) === RUN TestRWFileHandleSizeTruncateExisting run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:28:14 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:28:14 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:28:14 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:14 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:14 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:14 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:14 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:28:16.119798 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:28:16.119831 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:28:16 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:28:16 DEBUG : : Looking for writers 2024/04/25 05:28:16 DEBUG : >WaitForWriters: 2024/04/25 05:28:16 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeTruncateExisting (2.92s) === RUN TestRWFileHandleSizeCreateExisting run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:28:17 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:28:17 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:28:17 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:17 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:17 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:17 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:17 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:28:19.019051 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:28:19.019086 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:28:19 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:28:19 DEBUG : : Looking for writers 2024/04/25 05:28:19 DEBUG : >WaitForWriters: 2024/04/25 05:28:19 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeCreateExisting (2.93s) === RUN TestRWFileModTimeWithOpenWriters run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:28:20 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:28:20 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:28:20 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:20 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:20 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:20 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:20 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:28:20 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:28:20 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:28:20 DEBUG : file1: newRWFileHandle: 2024/04/25 05:28:20 DEBUG : file1(0xc000bbc480): openPending: 2024/04/25 05:28:20 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:28:20 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:28:20 DEBUG : file1(0xc000bbc480): >openPending: err= 2024/04/25 05:28:20 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:28:20 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:28:20 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:28:20 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:28:21.454049 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:28:21.454084 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "time_test" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:28:21 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:28:21 DEBUG : : Looking for writers 2024/04/25 05:28:21 DEBUG : file1: reading active writers 2024/04/25 05:28:21 DEBUG : file1: active writers 1 2024/04/25 05:28:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:28:21 DEBUG : : Looking for writers 2024/04/25 05:28:21 DEBUG : file1: reading active writers 2024/04/25 05:28:21 DEBUG : file1: active writers 1 2024/04/25 05:28:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:28:21 DEBUG : : Looking for writers 2024/04/25 05:28:21 DEBUG : file1: reading active writers 2024/04/25 05:28:21 DEBUG : file1: active writers 1 2024/04/25 05:28:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:28:21 DEBUG : : Looking for writers 2024/04/25 05:28:21 DEBUG : file1: reading active writers 2024/04/25 05:28:21 DEBUG : file1: active writers 1 2024/04/25 05:28:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:28:21 DEBUG : : Looking for writers 2024/04/25 05:28:21 DEBUG : file1: reading active writers 2024/04/25 05:28:21 DEBUG : file1: active writers 1 2024/04/25 05:28:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:28:21 DEBUG : : Looking for writers 2024/04/25 05:28:21 DEBUG : file1: reading active writers 2024/04/25 05:28:21 DEBUG : file1: active writers 1 2024/04/25 05:28:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:28:22 DEBUG : : Looking for writers 2024/04/25 05:28:22 DEBUG : file1: reading active writers 2024/04/25 05:28:22 DEBUG : file1: active writers 1 2024/04/25 05:28:22 DEBUG : Still 1 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:28:22 DEBUG : : Looking for writers 2024/04/25 05:28:22 DEBUG : file1: reading active writers 2024/04/25 05:28:22 DEBUG : file1: active writers 1 2024/04/25 05:28:22 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:23 DEBUG : : Looking for writers 2024/04/25 05:28:23 DEBUG : file1: reading active writers 2024/04/25 05:28:23 DEBUG : file1: active writers 1 2024/04/25 05:28:23 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:24 DEBUG : : Looking for writers 2024/04/25 05:28:24 DEBUG : file1: reading active writers 2024/04/25 05:28:24 DEBUG : file1: active writers 1 2024/04/25 05:28:24 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:25 DEBUG : : Looking for writers 2024/04/25 05:28:25 DEBUG : file1: reading active writers 2024/04/25 05:28:25 DEBUG : file1: active writers 1 2024/04/25 05:28:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:26 DEBUG : : Looking for writers 2024/04/25 05:28:26 DEBUG : file1: reading active writers 2024/04/25 05:28:26 DEBUG : file1: active writers 1 2024/04/25 05:28:26 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:27 DEBUG : : Looking for writers 2024/04/25 05:28:27 DEBUG : file1: reading active writers 2024/04/25 05:28:27 DEBUG : file1: active writers 1 2024/04/25 05:28:27 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:28 DEBUG : : Looking for writers 2024/04/25 05:28:28 DEBUG : file1: reading active writers 2024/04/25 05:28:28 DEBUG : file1: active writers 1 2024/04/25 05:28:28 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:29 DEBUG : : Looking for writers 2024/04/25 05:28:29 DEBUG : file1: reading active writers 2024/04/25 05:28:29 DEBUG : file1: active writers 1 2024/04/25 05:28:29 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:30 DEBUG : : Looking for writers 2024/04/25 05:28:30 DEBUG : file1: reading active writers 2024/04/25 05:28:30 DEBUG : file1: active writers 1 2024/04/25 05:28:30 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:31 DEBUG : : Looking for writers 2024/04/25 05:28:31 DEBUG : file1: reading active writers 2024/04/25 05:28:31 DEBUG : file1: active writers 1 2024/04/25 05:28:31 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:32 DEBUG : : Looking for writers 2024/04/25 05:28:32 DEBUG : file1: reading active writers 2024/04/25 05:28:32 DEBUG : file1: active writers 1 2024/04/25 05:28:32 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:33 DEBUG : : Looking for writers 2024/04/25 05:28:33 DEBUG : file1: reading active writers 2024/04/25 05:28:33 DEBUG : file1: active writers 1 2024/04/25 05:28:33 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:34 DEBUG : : Looking for writers 2024/04/25 05:28:34 DEBUG : file1: reading active writers 2024/04/25 05:28:34 DEBUG : file1: active writers 1 2024/04/25 05:28:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:35 DEBUG : : Looking for writers 2024/04/25 05:28:35 DEBUG : file1: reading active writers 2024/04/25 05:28:35 DEBUG : file1: active writers 1 2024/04/25 05:28:35 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:36 DEBUG : : Looking for writers 2024/04/25 05:28:36 DEBUG : file1: reading active writers 2024/04/25 05:28:36 DEBUG : file1: active writers 1 2024/04/25 05:28:36 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:37 DEBUG : : Looking for writers 2024/04/25 05:28:37 DEBUG : file1: reading active writers 2024/04/25 05:28:37 DEBUG : file1: active writers 1 2024/04/25 05:28:37 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:38 DEBUG : : Looking for writers 2024/04/25 05:28:38 DEBUG : file1: reading active writers 2024/04/25 05:28:38 DEBUG : file1: active writers 1 2024/04/25 05:28:38 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:39 DEBUG : : Looking for writers 2024/04/25 05:28:39 DEBUG : file1: reading active writers 2024/04/25 05:28:39 DEBUG : file1: active writers 1 2024/04/25 05:28:39 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:40 DEBUG : : Looking for writers 2024/04/25 05:28:40 DEBUG : file1: reading active writers 2024/04/25 05:28:40 DEBUG : file1: active writers 1 2024/04/25 05:28:40 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:41 DEBUG : : Looking for writers 2024/04/25 05:28:41 DEBUG : file1: reading active writers 2024/04/25 05:28:41 DEBUG : file1: active writers 1 2024/04/25 05:28:41 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:42 DEBUG : : Looking for writers 2024/04/25 05:28:42 DEBUG : file1: reading active writers 2024/04/25 05:28:42 DEBUG : file1: active writers 1 2024/04/25 05:28:42 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:43 DEBUG : : Looking for writers 2024/04/25 05:28:43 DEBUG : file1: reading active writers 2024/04/25 05:28:43 DEBUG : file1: active writers 1 2024/04/25 05:28:43 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:44 DEBUG : : Looking for writers 2024/04/25 05:28:44 DEBUG : file1: reading active writers 2024/04/25 05:28:44 DEBUG : file1: active writers 1 2024/04/25 05:28:44 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:45 DEBUG : : Looking for writers 2024/04/25 05:28:45 DEBUG : file1: reading active writers 2024/04/25 05:28:45 DEBUG : file1: active writers 1 2024/04/25 05:28:45 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:46 DEBUG : : Looking for writers 2024/04/25 05:28:46 DEBUG : file1: reading active writers 2024/04/25 05:28:46 DEBUG : file1: active writers 1 2024/04/25 05:28:46 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:47 DEBUG : : Looking for writers 2024/04/25 05:28:47 DEBUG : file1: reading active writers 2024/04/25 05:28:47 DEBUG : file1: active writers 1 2024/04/25 05:28:47 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:48 DEBUG : : Looking for writers 2024/04/25 05:28:48 DEBUG : file1: reading active writers 2024/04/25 05:28:48 DEBUG : file1: active writers 1 2024/04/25 05:28:48 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:49 DEBUG : : Looking for writers 2024/04/25 05:28:49 DEBUG : file1: reading active writers 2024/04/25 05:28:49 DEBUG : file1: active writers 1 2024/04/25 05:28:49 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:50 DEBUG : : Looking for writers 2024/04/25 05:28:50 DEBUG : file1: reading active writers 2024/04/25 05:28:50 DEBUG : file1: active writers 1 2024/04/25 05:28:50 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:28:51 ERROR : Exiting even though 1 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc00075cc30 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0005df008 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824639877184} name:file1 opens:1 downloaders: o: fd:0xc0001bc9c0 info:{ModTime:{wall:13943874922324490856 ext:429181105415 loc:0x3a135e0} ATime:{wall:13943874922324490856 ext:429181105415 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:0 pendingAccesses:0 modified:true beingReset:false}, } 2024/04/25 05:28:51 DEBUG : >WaitForWriters: 2024/04/25 05:28:51 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileModTimeWithOpenWriters (31.53s) === RUN TestRWCacheUpdate run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:28:52 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:28:52 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:28:52 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:52 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:52 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:52 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-qenizah3docinoq9muperib1" 2024/04/25 05:28:52 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:28:52 DEBUG : : forgetting directory cache 2024/04/25 05:28:52.690797 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:28:52.690835 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "TestRWCacheUpdate" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:28:52 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:28:52 DEBUG : : Looking for writers 2024/04/25 05:28:52 DEBUG : >WaitForWriters: 2024/04/25 05:28:52 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWCacheUpdate (1.30s) === RUN TestCaseSensitivity run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:28:54.022517 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:28:54.022544 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "FiLeA" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) --- FAIL: TestCaseSensitivity (1.24s) === RUN TestUnicodeNormalization run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:28:55.302085 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:28:55.302125 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "normal name with no special characters.txt" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) --- FAIL: TestUnicodeNormalization (1.38s) === RUN TestVFSStat run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:28:55 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:28:55 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:28:56.673692 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:28:56.673723 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) run.go:285: Failed to put "file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:28:57 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:28:57 DEBUG : : Looking for writers 2024/04/25 05:28:57 DEBUG : >WaitForWriters: --- FAIL: TestVFSStat (2.37s) === RUN TestVFSStatParent run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:28:58 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:28:58 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:28:59.052584 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:28:59.052618 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) run.go:285: Failed to put "file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:29:00 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:29:00 DEBUG : : Looking for writers 2024/04/25 05:29:00 DEBUG : >WaitForWriters: --- FAIL: TestVFSStatParent (2.30s) === RUN TestVFSOpenFile run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:29:00 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:29:00 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:29:01.358184 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:29:01.358210 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) run.go:285: Failed to put "file1" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:29:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:29:02 DEBUG : : Looking for writers 2024/04/25 05:29:02 DEBUG : >WaitForWriters: --- FAIL: TestVFSOpenFile (2.37s) === RUN TestVFSRename run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:29:03 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:29:03 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:29:04.372286 WARN RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400), Attempt 1 2024/04/25 05:29:04.372337 ERROR RESTY 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) run.go:285: Failed to put "dir/file2" to "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'": 400 POST https://mail.proton.me/api/drive/blocks: You are using an outdated version of the app. Please update to upload this file’ (Code=2000, Status=400) 2024/04/25 05:29:04 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:29:04 DEBUG : : Looking for writers 2024/04/25 05:29:04 DEBUG : >WaitForWriters: --- FAIL: TestVFSRename (2.88s) === RUN TestWriteFileHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:29:05 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:29:05 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:29:05 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:29:06 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:29:06 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:29:06 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:29:06 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:29:06 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:29:06 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2024/04/25 05:29:06 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2024/04/25 05:29:06 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2024/04/25 05:29:06 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': File to upload is small (5 bytes), uploading instead of streaming 2024/04/25 05:29:06.826322 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:29:06.826359 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:29:07 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:29:07 ERROR : file1: WriteFileHandle.New Rcat failed: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:29:07 DEBUG : file1: Remove: 2024/04/25 05:29:07 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:29:07 DEBUG : file1: >Remove: err= write_test.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:144 Error: Received unexpected error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 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:153 Error: Not equal: expected: []string{"file1,5,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=13) "file1,5,false" -} +([]string) Test: TestWriteFileHandleMethods fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:157 Error: Should be true Test: TestWriteFileHandleMethods Messages: listing wrong, want file1 (5) got fstest.go:197: Not found "file1" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:157 Error: Not equal: expected: 0 actual : 1 Test: TestWriteFileHandleMethods Messages: 1 objects not found 2024/04/25 05:29:43 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:29:43 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:29:43 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:29:43 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:29:43 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:29:43 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:29:43 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:29:58.199649 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:29:58.199686 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:29:59 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:29:59 ERROR : file1: WriteFileHandle.New Rcat failed: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:29:59 DEBUG : file1: Remove: 2024/04/25 05:29:59 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:29:59 DEBUG : file1: >Remove: err= write_test.go:163: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:163 Error: Received unexpected error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 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:164 Error: Not equal: expected: []string{"file1,5,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=13) "file1,5,false" -} +([]string) Test: TestWriteFileHandleMethods 2024/04/25 05:29:59 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:29:59 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:29:59 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:29:59 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:29:59 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:29:59 DEBUG : : Added virtual directory entry vAddFile: "file1" write_test.go:171: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:171 Error: Not equal: expected: *errors.errorString(&errors.errorString{s:"permission denied"}) actual : () Test: TestWriteFileHandleMethods 2024/04/25 05:29:59 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:29:59 DEBUG : : Looking for writers 2024/04/25 05:29:59 DEBUG : file1: reading active writers 2024/04/25 05:29:59 DEBUG : file1: active writers 1 2024/04/25 05:29:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms 2024/04/25 05:29:59 DEBUG : : Looking for writers 2024/04/25 05:29:59 DEBUG : file1: reading active writers 2024/04/25 05:29:59 DEBUG : file1: active writers 1 2024/04/25 05:29:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms 2024/04/25 05:29:59 DEBUG : : Looking for writers 2024/04/25 05:29:59 DEBUG : file1: reading active writers 2024/04/25 05:29:59 DEBUG : file1: active writers 1 2024/04/25 05:29:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms 2024/04/25 05:29:59 DEBUG : : Looking for writers 2024/04/25 05:29:59 DEBUG : file1: reading active writers 2024/04/25 05:29:59 DEBUG : file1: active writers 1 2024/04/25 05:29:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms 2024/04/25 05:29:59 DEBUG : : Looking for writers 2024/04/25 05:29:59 DEBUG : file1: reading active writers 2024/04/25 05:29:59 DEBUG : file1: active writers 1 2024/04/25 05:29:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms 2024/04/25 05:29:59 DEBUG : : Looking for writers 2024/04/25 05:29:59 DEBUG : file1: reading active writers 2024/04/25 05:29:59 DEBUG : file1: active writers 1 2024/04/25 05:29:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms 2024/04/25 05:29:59 DEBUG : : Looking for writers 2024/04/25 05:29:59 DEBUG : file1: reading active writers 2024/04/25 05:29:59 DEBUG : file1: active writers 1 2024/04/25 05:29:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms 2024/04/25 05:30:00 DEBUG : : Looking for writers 2024/04/25 05:30:00 DEBUG : file1: reading active writers 2024/04/25 05:30:00 DEBUG : file1: active writers 1 2024/04/25 05:30:00 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:01 DEBUG : : Looking for writers 2024/04/25 05:30:01 DEBUG : file1: reading active writers 2024/04/25 05:30:01 DEBUG : file1: active writers 1 2024/04/25 05:30:01 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:02 DEBUG : : Looking for writers 2024/04/25 05:30:02 DEBUG : file1: reading active writers 2024/04/25 05:30:02 DEBUG : file1: active writers 1 2024/04/25 05:30:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:03 DEBUG : : Looking for writers 2024/04/25 05:30:03 DEBUG : file1: reading active writers 2024/04/25 05:30:03 DEBUG : file1: active writers 1 2024/04/25 05:30:03 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:04 DEBUG : : Looking for writers 2024/04/25 05:30:04 DEBUG : file1: reading active writers 2024/04/25 05:30:04 DEBUG : file1: active writers 1 2024/04/25 05:30:04 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:05 DEBUG : : Looking for writers 2024/04/25 05:30:05 DEBUG : file1: reading active writers 2024/04/25 05:30:05 DEBUG : file1: active writers 1 2024/04/25 05:30:05 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:06 DEBUG : : Looking for writers 2024/04/25 05:30:06 DEBUG : file1: reading active writers 2024/04/25 05:30:06 DEBUG : file1: active writers 1 2024/04/25 05:30:06 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:07 DEBUG : : Looking for writers 2024/04/25 05:30:07 DEBUG : file1: reading active writers 2024/04/25 05:30:07 DEBUG : file1: active writers 1 2024/04/25 05:30:07 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:08 DEBUG : : Looking for writers 2024/04/25 05:30:08 DEBUG : file1: reading active writers 2024/04/25 05:30:08 DEBUG : file1: active writers 1 2024/04/25 05:30:08 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:09 DEBUG : : Looking for writers 2024/04/25 05:30:09 DEBUG : file1: reading active writers 2024/04/25 05:30:09 DEBUG : file1: active writers 1 2024/04/25 05:30:09 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:10 DEBUG : : Looking for writers 2024/04/25 05:30:10 DEBUG : file1: reading active writers 2024/04/25 05:30:10 DEBUG : file1: active writers 1 2024/04/25 05:30:10 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:11 DEBUG : : Looking for writers 2024/04/25 05:30:11 DEBUG : file1: reading active writers 2024/04/25 05:30:11 DEBUG : file1: active writers 1 2024/04/25 05:30:11 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:12 DEBUG : : Looking for writers 2024/04/25 05:30:12 DEBUG : file1: reading active writers 2024/04/25 05:30:12 DEBUG : file1: active writers 1 2024/04/25 05:30:12 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:13 DEBUG : : Looking for writers 2024/04/25 05:30:13 DEBUG : file1: reading active writers 2024/04/25 05:30:13 DEBUG : file1: active writers 1 2024/04/25 05:30:13 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:14 DEBUG : : Looking for writers 2024/04/25 05:30:14 DEBUG : file1: reading active writers 2024/04/25 05:30:14 DEBUG : file1: active writers 1 2024/04/25 05:30:14 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:15 DEBUG : : Looking for writers 2024/04/25 05:30:15 DEBUG : file1: reading active writers 2024/04/25 05:30:15 DEBUG : file1: active writers 1 2024/04/25 05:30:15 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:16 DEBUG : : Looking for writers 2024/04/25 05:30:16 DEBUG : file1: reading active writers 2024/04/25 05:30:16 DEBUG : file1: active writers 1 2024/04/25 05:30:16 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:17 DEBUG : : Looking for writers 2024/04/25 05:30:17 DEBUG : file1: reading active writers 2024/04/25 05:30:17 DEBUG : file1: active writers 1 2024/04/25 05:30:17 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:18 DEBUG : : Looking for writers 2024/04/25 05:30:18 DEBUG : file1: reading active writers 2024/04/25 05:30:18 DEBUG : file1: active writers 1 2024/04/25 05:30:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:19 DEBUG : : Looking for writers 2024/04/25 05:30:19 DEBUG : file1: reading active writers 2024/04/25 05:30:19 DEBUG : file1: active writers 1 2024/04/25 05:30:19 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:20 DEBUG : : Looking for writers 2024/04/25 05:30:20 DEBUG : file1: reading active writers 2024/04/25 05:30:20 DEBUG : file1: active writers 1 2024/04/25 05:30:20 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:21 DEBUG : : Looking for writers 2024/04/25 05:30:21 DEBUG : file1: reading active writers 2024/04/25 05:30:21 DEBUG : file1: active writers 1 2024/04/25 05:30:21 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:22 DEBUG : : Looking for writers 2024/04/25 05:30:22 DEBUG : file1: reading active writers 2024/04/25 05:30:22 DEBUG : file1: active writers 1 2024/04/25 05:30:22 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:23 DEBUG : : Looking for writers 2024/04/25 05:30:23 DEBUG : file1: reading active writers 2024/04/25 05:30:23 DEBUG : file1: active writers 1 2024/04/25 05:30:23 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:24 DEBUG : : Looking for writers 2024/04/25 05:30:24 DEBUG : file1: reading active writers 2024/04/25 05:30:24 DEBUG : file1: active writers 1 2024/04/25 05:30:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:25 DEBUG : : Looking for writers 2024/04/25 05:30:25 DEBUG : file1: reading active writers 2024/04/25 05:30:25 DEBUG : file1: active writers 1 2024/04/25 05:30:25 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:26 DEBUG : : Looking for writers 2024/04/25 05:30:26 DEBUG : file1: reading active writers 2024/04/25 05:30:26 DEBUG : file1: active writers 1 2024/04/25 05:30:26 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:27 DEBUG : : Looking for writers 2024/04/25 05:30:27 DEBUG : file1: reading active writers 2024/04/25 05:30:27 DEBUG : file1: active writers 1 2024/04/25 05:30:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:28 DEBUG : : Looking for writers 2024/04/25 05:30:28 DEBUG : file1: reading active writers 2024/04/25 05:30:28 DEBUG : file1: active writers 1 2024/04/25 05:30:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:30:29 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s Cache: 2024/04/25 05:30:29 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleMethods (84.02s) === RUN TestWriteFileHandleWriteAt run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:30:29 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:30:29 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:30:29 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:30:30 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:30:30 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:30:30 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:30:30 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:30:30 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:30:30 DEBUG : file1: waiting for in-sequence write to 100 for 1s 2024/04/25 05:30:31 DEBUG : file1: aborting in-sequence write wait, off=100 2024/04/25 05:30:31 DEBUG : file1: failed to wait for in-sequence write to 100 2024/04/25 05:30:31 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes 2024/04/25 05:30:31 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': File to upload is small (11 bytes), uploading instead of streaming 2024/04/25 05:30:31.970458 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:30:31.970493 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:30:32 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:30:32 ERROR : file1: WriteFileHandle.New Rcat failed: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:30:32 DEBUG : file1: Remove: 2024/04/25 05:30:32 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:30:32 DEBUG : file1: >Remove: err= write_test.go:221: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:221 Error: Received unexpected error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft Test: TestWriteFileHandleWriteAt 2024/04/25 05:30:32 ERROR : file1: WriteFileHandle.Write: error: Bad file descriptor 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:231 Error: Not equal: expected: []string{"file1,11,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=14) "file1,11,false" -} +([]string) Test: TestWriteFileHandleWriteAt fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:235 Error: Should be true Test: TestWriteFileHandleWriteAt Messages: listing wrong, want file1 (11) got fstest.go:197: Not found "file1" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:235 Error: Not equal: expected: 0 actual : 1 Test: TestWriteFileHandleWriteAt Messages: 1 objects not found 2024/04/25 05:31:08 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:08 DEBUG : : Looking for writers 2024/04/25 05:31:08 DEBUG : >WaitForWriters: 2024/04/25 05:31:13 DEBUG : : forgetting directory cache 2024/04/25 05:31:16 DEBUG : : forgetting directory cache 2024/04/25 05:31:19 DEBUG : : forgetting directory cache 2024/04/25 05:31:22 DEBUG : : forgetting directory cache --- FAIL: TestWriteFileHandleWriteAt (53.30s) === RUN TestWriteFileHandleFlush run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:31:23 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:31:23 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:23 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:31:23 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:31:23 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:23 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:31:23 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:31:23 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2024/04/25 05:31:23 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:23 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': File to upload is small (5 bytes), uploading instead of streaming 2024/04/25 05:31:24.190264 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:31:24.190291 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:31:24 DEBUG : : forgetting directory cache 2024/04/25 05:31:25 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:25 ERROR : file1: WriteFileHandle.New Rcat failed: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:25 DEBUG : file1: Remove: 2024/04/25 05:31:25 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:31:25 DEBUG : file1: >Remove: err= 2024/04/25 05:31:25 ERROR : file1: WriteFileHandle.Flush error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft write_test.go:256: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:256 Error: Received unexpected error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft Test: TestWriteFileHandleFlush 2024/04/25 05:31:25 DEBUG : file1: WriteFileHandle.Flush nothing to do 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:267 Error: Not equal: expected: []string{"file1,5,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=13) "file1,5,false" -} +([]string) Test: TestWriteFileHandleFlush 2024/04/25 05:31:25 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:25 DEBUG : : Looking for writers 2024/04/25 05:31:25 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleFlush (2.73s) === RUN TestWriteFileHandleRelease run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:31:25 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:31:25 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:25 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:31:26 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:31:26 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:26 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:31:26 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:31:26 DEBUG : file1: WriteFileHandle.Release closing 2024/04/25 05:31:26 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:26 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:31:27.028922 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:31:27.028961 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:31:27 DEBUG : : forgetting directory cache 2024/04/25 05:31:28 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:28 ERROR : file1: WriteFileHandle.New Rcat failed: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:28 DEBUG : file1: Remove: 2024/04/25 05:31:28 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:31:28 DEBUG : file1: >Remove: err= 2024/04/25 05:31:28 ERROR : file1: WriteFileHandle.Release error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft write_test.go:279: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:279 Error: Received unexpected error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft Test: TestWriteFileHandleRelease 2024/04/25 05:31:28 DEBUG : file1: WriteFileHandle.Release nothing to do 2024/04/25 05:31:28 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:28 DEBUG : : Looking for writers 2024/04/25 05:31:28 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleRelease (2.73s) === RUN TestWriteFileModTimeWithOpenWriters run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:31:28 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:31:28 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:28 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:31:28 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:31:28 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:28 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:31:28 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:31:28 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:28 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': File to upload is small (2 bytes), uploading instead of streaming 2024/04/25 05:31:29.661938 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:31:29.661972 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:31:30 DEBUG : : forgetting directory cache 2024/04/25 05:31:30 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:30 ERROR : file1: WriteFileHandle.New Rcat failed: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:30 DEBUG : file1: Remove: 2024/04/25 05:31:30 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:31:30 DEBUG : file1: >Remove: err= write_test.go:333: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:333 Error: Received unexpected error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft Test: TestWriteFileModTimeWithOpenWriters 2024/04/25 05:31:30 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:30 DEBUG : : Looking for writers 2024/04/25 05:31:30 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileModTimeWithOpenWriters (2.70s) === RUN TestFileReadAtZeroLength run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:31:31 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:31:31 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:31 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:31:31 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:31:31 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:31 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:31:31 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:31:31 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:31 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:31:32.416312 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:31:32.416344 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:31:33 DEBUG : : forgetting directory cache 2024/04/25 05:31:33 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:33 ERROR : file1: WriteFileHandle.New Rcat failed: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:33 DEBUG : file1: Remove: 2024/04/25 05:31:33 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:31:33 DEBUG : file1: >Remove: err= write_test.go:360: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:360 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:380 Error: Received unexpected error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft Test: TestFileReadAtZeroLength 2024/04/25 05:31:33 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2024/04/25 05:31:33 DEBUG : file1: >OpenFile: fd=, err=file does not exist write_test.go:365: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:365 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:380 Error: Received unexpected error: file does not exist Test: TestFileReadAtZeroLength 2024/04/25 05:31:33 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:33 DEBUG : : Looking for writers 2024/04/25 05:31:33 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtZeroLength (2.71s) === RUN TestFileReadAtNonZeroLength run.go:180: Remote "proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1'", Local "Local file system at /tmp/rclone808050284", Modify Window "1s" 2024/04/25 05:31:34 INFO : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': poll-interval is not supported by this remote 2024/04/25 05:31:34 NOTICE: proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:34 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:31:34 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:31:34 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:34 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:31:34 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:31:34 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:31:34 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': File to upload is small (100 bytes), uploading instead of streaming 2024/04/25 05:31:35.087697 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/04/25 05:31:35.087725 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/04/25 05:31:35 DEBUG : : forgetting directory cache 2024/04/25 05:31:36 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:36 ERROR : file1: WriteFileHandle.New Rcat failed: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:31:36 DEBUG : file1: Remove: 2024/04/25 05:31:36 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:31:36 DEBUG : file1: >Remove: err= write_test.go:360: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:360 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Received unexpected error: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft Test: TestFileReadAtNonZeroLength 2024/04/25 05:31:36 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2024/04/25 05:31:36 DEBUG : file1: >OpenFile: fd=, err=file does not exist write_test.go:365: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:365 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Received unexpected error: file does not exist Test: TestFileReadAtNonZeroLength 2024/04/25 05:31:36 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:36 DEBUG : : Looking for writers 2024/04/25 05:31:36 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtNonZeroLength (2.75s) FAIL 2024/04/25 05:31:36 DEBUG : proton drive root link ID 'rclone-test-qenizah3docinoq9muperib1': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestCaseSensitivity|TestDirCreate|TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMethods|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestFileRemove|TestFileRemoveAll|TestRWCacheUpdate|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleMethodsWrite|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestRWFileHandleWriteAt|TestRWFileHandleWriteNoWrite|TestRWFileModTimeWithOpenWriters|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleRelease|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters)$|^TestFileRename$/^(full,forceCache=false|minimal,forceCache=false|minimal,forceCache=true|off,forceCache=false|writes,forceCache=false|writes,forceCache=true)$|^TestFileSetModTime$/^(cache=full,open=false,write=false|cache=full,open=true,write=false|cache=full,open=true,write=true|cache=off,open=false,write=false|cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Finished ERROR in 10m25.703831726s (try 4/5): exit status 1: Failed [TestDirHandleMethods TestDirHandleReaddir TestDirHandleReaddirnames TestDirMethods TestDirForgetAll TestDirForgetPath TestDirWalk TestDirSetModTime TestDirStat TestDirReadDirAll TestDirOpen TestDirCreate TestDirMkdir TestDirMkdirSub TestDirRemove TestDirRemoveAll TestDirRemoveName TestDirRename TestFileMethods TestFileSetModTime/cache=off,open=false,write=false TestFileSetModTime/cache=off,open=true,write=false TestFileSetModTime/cache=off,open=true,write=true TestFileSetModTime/cache=full,open=false,write=false TestFileSetModTime/cache=full,open=true,write=false TestFileSetModTime/cache=full,open=true,write=true TestFileOpenRead TestFileOpenWrite TestFileRemove TestFileRemoveAll TestFileOpen TestFileRename/off,forceCache=false TestFileRename/minimal,forceCache=false TestFileRename/minimal,forceCache=true TestFileRename/writes,forceCache=false TestFileRename/writes,forceCache=true TestFileRename/full,forceCache=false TestReadFileHandleMethods TestReadFileHandleSeek TestReadFileHandleReadAt TestReadFileHandleFlush TestReadFileHandleRelease TestRWFileHandleMethodsRead TestRWFileHandleSeek TestRWFileHandleReadAt TestRWFileHandleFlushRead TestRWFileHandleReleaseRead TestRWFileHandleMethodsWrite TestRWFileHandleWriteAt TestRWFileHandleWriteNoWrite TestRWFileHandleSizeTruncateExisting TestRWFileHandleSizeCreateExisting TestRWFileModTimeWithOpenWriters TestRWCacheUpdate TestCaseSensitivity TestUnicodeNormalization TestVFSStat TestVFSStatParent TestVFSOpenFile TestVFSRename TestWriteFileHandleMethods TestWriteFileHandleWriteAt TestWriteFileHandleFlush TestWriteFileHandleRelease TestWriteFileModTimeWithOpenWriters TestFileReadAtZeroLength TestFileReadAtNonZeroLength]