"./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 3/5) 2024/04/25 05:09:08 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:09:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/25 05:09:08 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': Has cached credentials 2024/04/25 05:09:10 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': Used cached credential to initialize the ProtonDrive API 2024/04/25 05:09:10 DEBUG : Creating backend with remote "/tmp/rclone27614605" === RUN TestDirHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:10 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:10 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:13.355260 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:09:13.355292 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-xayodap5kapovik2neruhan1'": 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:09:13 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:13 DEBUG : : Looking for writers 2024/04/25 05:09:13 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleMethods (4.77s) === RUN TestDirHandleReaddir run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:15 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:15 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:17.080062 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:09:17.080089 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-xayodap5kapovik2neruhan1'": 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:09:17 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:17 DEBUG : : Looking for writers 2024/04/25 05:09:17 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddir (3.73s) === RUN TestDirHandleReaddirnames run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:19 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:19 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:20.842472 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:09:20.842499 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-xayodap5kapovik2neruhan1'": 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:09:20 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:20 DEBUG : : Looking for writers 2024/04/25 05:09:20 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddirnames (3.76s) === RUN TestDirMethods run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:22 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:22 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:24.599078 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:09:24.599120 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-xayodap5kapovik2neruhan1'": 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:09:24 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:24 DEBUG : : Looking for writers 2024/04/25 05:09:24 DEBUG : >WaitForWriters: --- FAIL: TestDirMethods (3.73s) === RUN TestDirForgetAll run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:26 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:26 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:28.282527 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:09:28.282563 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-xayodap5kapovik2neruhan1'": 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:09:28 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:28 DEBUG : : Looking for writers 2024/04/25 05:09:28 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetAll (3.68s) === RUN TestDirForgetPath run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:30 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:30 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:32.077013 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:09:32.077042 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-xayodap5kapovik2neruhan1'": 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:09:32 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:32 DEBUG : : Looking for writers 2024/04/25 05:09:32 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetPath (3.77s) === RUN TestDirWalk run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:34 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:34 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:35.867677 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:09:35.867705 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-xayodap5kapovik2neruhan1'": 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:09:35 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:35 DEBUG : : Looking for writers 2024/04/25 05:09:35 DEBUG : >WaitForWriters: --- FAIL: TestDirWalk (3.86s) === RUN TestDirSetModTime run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:37 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:37 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:39.596203 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:09:39.596236 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-xayodap5kapovik2neruhan1'": 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:09:39 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:39 DEBUG : : Looking for writers 2024/04/25 05:09:39 DEBUG : >WaitForWriters: --- FAIL: TestDirSetModTime (3.68s) === RUN TestDirStat run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:41 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:41 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:43.299002 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:09:43.299042 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-xayodap5kapovik2neruhan1'": 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:09:43 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:43 DEBUG : : Looking for writers 2024/04/25 05:09:43 DEBUG : >WaitForWriters: --- FAIL: TestDirStat (3.81s) === RUN TestDirReadDirAll run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:45 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:45 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:47.124817 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:09:47.124851 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-xayodap5kapovik2neruhan1'": 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:09:47 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:47 DEBUG : : Looking for writers 2024/04/25 05:09:47 DEBUG : >WaitForWriters: --- FAIL: TestDirReadDirAll (3.67s) === RUN TestDirOpen run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:49 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:49 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:50.782202 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:09:50.782230 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-xayodap5kapovik2neruhan1'": 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:09:50 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:50 DEBUG : : Looking for writers 2024/04/25 05:09:50 DEBUG : >WaitForWriters: --- FAIL: TestDirOpen (3.74s) === RUN TestDirCreate run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:52 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:52 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:54.633161 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:09:54.633197 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-xayodap5kapovik2neruhan1'": 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:09:54 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:54 DEBUG : : Looking for writers 2024/04/25 05:09:54 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (3.91s) === RUN TestDirMkdir run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:09:56 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:09:56 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:09:58.446644 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:09:58.446679 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-xayodap5kapovik2neruhan1'": 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:09:58 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:09:58 DEBUG : : Looking for writers 2024/04/25 05:09:58 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdir (3.87s) === RUN TestDirMkdirSub run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:00 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:00 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:02.271512 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:10:02.271537 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-xayodap5kapovik2neruhan1'": 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:10:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:02 DEBUG : : Looking for writers 2024/04/25 05:10:02 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdirSub (3.75s) === RUN TestDirRemove run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:04 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:04 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:06.111010 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:10:06.111039 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-xayodap5kapovik2neruhan1'": 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:10:06 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:06 DEBUG : : Looking for writers 2024/04/25 05:10:06 DEBUG : >WaitForWriters: --- FAIL: TestDirRemove (3.87s) === RUN TestDirRemoveAll run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:08 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:08 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:09.912896 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:10:09.912933 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-xayodap5kapovik2neruhan1'": 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:10:09 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:09 DEBUG : : Looking for writers 2024/04/25 05:10:09 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveAll (3.69s) === RUN TestDirRemoveName run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:11 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:11 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:13.612734 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:10:13.612761 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-xayodap5kapovik2neruhan1'": 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:10:13 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:13 DEBUG : : Looking for writers 2024/04/25 05:10:13 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (3.74s) === RUN TestDirRename run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:15 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:15 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:17.379069 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:10:17.379096 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-xayodap5kapovik2neruhan1'": 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:10:17 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:17 DEBUG : : Looking for writers 2024/04/25 05:10:17 DEBUG : >WaitForWriters: --- FAIL: TestDirRename (3.74s) === RUN TestFileMethods run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:19 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:19 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:21.086213 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:10:21.086261 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-xayodap5kapovik2neruhan1'": 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:10:21 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:21 DEBUG : : Looking for writers 2024/04/25 05:10:21 DEBUG : >WaitForWriters: --- FAIL: TestFileMethods (3.74s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:23 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:23 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:24.861653 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:10:24.861684 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-xayodap5kapovik2neruhan1'": 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:10:24 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:24 DEBUG : : Looking for writers 2024/04/25 05:10:24 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:26 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:26 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:28.556700 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:10:28.556743 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-xayodap5kapovik2neruhan1'": 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:10:28 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:28 DEBUG : : Looking for writers 2024/04/25 05:10:28 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:30 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:30 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:32.405805 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:10:32.405842 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-xayodap5kapovik2neruhan1'": 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:10:32 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:32 DEBUG : : Looking for writers 2024/04/25 05:10:32 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:34 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:34 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:10:34 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:34 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:34 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:34 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:34 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:10:36.218601 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:10:36.218649 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-xayodap5kapovik2neruhan1'": 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:10:36 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:36 DEBUG : : Looking for writers 2024/04/25 05:10:36 DEBUG : >WaitForWriters: 2024/04/25 05:10:36 DEBUG : vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:38 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:38 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:10:38 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:38 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:38 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:38 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:38 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:10:39.881085 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:10:39.881125 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-xayodap5kapovik2neruhan1'": 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:10:39 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:39 DEBUG : : Looking for writers 2024/04/25 05:10:39 DEBUG : >WaitForWriters: 2024/04/25 05:10:39 DEBUG : vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:41 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:41 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:10:41 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:41 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:41 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:41 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:10:41 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:10:43.714808 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:10:43.714864 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-xayodap5kapovik2neruhan1'": 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:10:43 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:43 DEBUG : : Looking for writers 2024/04/25 05:10:43 DEBUG : >WaitForWriters: 2024/04/25 05:10:43 DEBUG : vfs cache: cleaner exiting --- FAIL: TestFileSetModTime (22.61s) --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (3.71s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=false (3.83s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (3.79s) --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (3.76s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (3.65s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (3.87s) === RUN TestFileOpenRead run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:45 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:45 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:47.412481 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:10:47.412507 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-xayodap5kapovik2neruhan1'": 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:10:47 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:47 DEBUG : : Looking for writers 2024/04/25 05:10:47 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenRead (3.64s) === RUN TestFileOpenWrite run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:49 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:49 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:51.262990 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:10:51.263013 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-xayodap5kapovik2neruhan1'": 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:10:51 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:51 DEBUG : : Looking for writers 2024/04/25 05:10:51 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenWrite (3.87s) === RUN TestFileRemove run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:53 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:53 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:55.046508 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:10:55.046543 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-xayodap5kapovik2neruhan1'": 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:10:55 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:55 DEBUG : : Looking for writers 2024/04/25 05:10:55 DEBUG : >WaitForWriters: --- FAIL: TestFileRemove (3.84s) === RUN TestFileRemoveAll run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:10:57 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:10:57 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:10:58.929168 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:10:58.929200 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-xayodap5kapovik2neruhan1'": 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:10:58 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:10:58 DEBUG : : Looking for writers 2024/04/25 05:10:58 DEBUG : >WaitForWriters: --- FAIL: TestFileRemoveAll (3.87s) === RUN TestFileOpen run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:01 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:01 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:02.726478 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:11:02.726505 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-xayodap5kapovik2neruhan1'": 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:11:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:02 DEBUG : : Looking for writers 2024/04/25 05:11:02 DEBUG : >WaitForWriters: --- FAIL: TestFileOpen (3.80s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:04 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:04 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:06.484161 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:11:06.484193 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-xayodap5kapovik2neruhan1'": 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:11:06 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:06 DEBUG : : Looking for writers 2024/04/25 05:11:06 DEBUG : >WaitForWriters: === RUN TestFileRename/minimal,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:08 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:08 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:08 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:08 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:08 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:08 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:08 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11: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:11:10.339052 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:11:10.339078 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-xayodap5kapovik2neruhan1'": 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:11:10 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:10 DEBUG : : Looking for writers 2024/04/25 05:11:10 DEBUG : >WaitForWriters: 2024/04/25 05:11:10 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/minimal,forceCache=true run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:12 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:12 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:12 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:12 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:12 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:12 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:12 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:12 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:11:14.170637 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:11:14.170667 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-xayodap5kapovik2neruhan1'": 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:11:14 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:14 DEBUG : : Looking for writers 2024/04/25 05:11:14 DEBUG : >WaitForWriters: 2024/04/25 05:11:14 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:16 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:16 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:16 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:16 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:16 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:16 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11: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:11:18.222479 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:11:18.222518 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-xayodap5kapovik2neruhan1'": 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:11:18 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:18 DEBUG : : Looking for writers 2024/04/25 05:11:18 DEBUG : >WaitForWriters: 2024/04/25 05:11:18 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=true run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:20 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:20 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:20 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:20 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:20 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:20 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11: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:11:21.968437 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:11:21.968474 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-xayodap5kapovik2neruhan1'": 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:11:21 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:21 DEBUG : : Looking for writers 2024/04/25 05:11:21 DEBUG : >WaitForWriters: 2024/04/25 05:11:21 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/full,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:24 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:24 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:24 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:24 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:24 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:24 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:24 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:11:25.724617 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:11:25.724669 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-xayodap5kapovik2neruhan1'": 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:11:25 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:25 DEBUG : : Looking for writers 2024/04/25 05:11:25 DEBUG : >WaitForWriters: 2024/04/25 05:11:25 DEBUG : vfs cache: cleaner exiting --- FAIL: TestFileRename (23.04s) --- FAIL: TestFileRename/off,forceCache=false (3.73s) --- FAIL: TestFileRename/minimal,forceCache=false (3.89s) --- FAIL: TestFileRename/minimal,forceCache=true (3.88s) --- FAIL: TestFileRename/writes,forceCache=false (3.98s) --- FAIL: TestFileRename/writes,forceCache=true (3.78s) --- FAIL: TestFileRename/full,forceCache=false (3.78s) === RUN TestReadFileHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:27 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:27 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:29.582072 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:11:29.582099 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-xayodap5kapovik2neruhan1'": 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:11:29 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:29 DEBUG : : Looking for writers 2024/04/25 05:11:29 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (3.84s) === RUN TestReadFileHandleSeek run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:31 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:31 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:33.402672 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:11:33.402737 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-xayodap5kapovik2neruhan1'": 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:11:33 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:33 DEBUG : : Looking for writers 2024/04/25 05:11:33 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleSeek (3.77s) === RUN TestReadFileHandleReadAt run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:35 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:35 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:37.062203 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:11:37.062233 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-xayodap5kapovik2neruhan1'": 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:11:37 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:37 DEBUG : : Looking for writers 2024/04/25 05:11:37 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (3.58s) === RUN TestReadFileHandleFlush run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:39 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:39 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:40.733648 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:11:40.733684 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-xayodap5kapovik2neruhan1'": 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:11:40 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:40 DEBUG : : Looking for writers 2024/04/25 05:11:40 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleFlush (3.71s) === RUN TestReadFileHandleRelease run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:42 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:42 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:11:44.425865 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:11:44.425892 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-xayodap5kapovik2neruhan1'": 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:11:44 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:44 DEBUG : : Looking for writers 2024/04/25 05:11:44 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (3.71s) === RUN TestRWFileHandleMethodsRead run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:46 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:46 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:46 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:46 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:46 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:46 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:46 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:11:48.275689 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:11:48.275723 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-xayodap5kapovik2neruhan1'": 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:11:48 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:48 DEBUG : : Looking for writers 2024/04/25 05:11:48 DEBUG : >WaitForWriters: 2024/04/25 05:11:48 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (3.99s) === RUN TestRWFileHandleSeek run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:50 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:50 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:50 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:50 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:50 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:50 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:50 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:11:51.994627 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:11:51.994656 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-xayodap5kapovik2neruhan1'": 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:11:51 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:51 DEBUG : : Looking for writers 2024/04/25 05:11:51 DEBUG : >WaitForWriters: 2024/04/25 05:11:51 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (3.67s) === RUN TestRWFileHandleReadAt run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:54 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:54 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:54 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:54 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:54 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:54 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:54 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:11:55.743981 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:11:55.744005 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-xayodap5kapovik2neruhan1'": 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:11:55 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:55 DEBUG : : Looking for writers 2024/04/25 05:11:55 DEBUG : >WaitForWriters: 2024/04/25 05:11:55 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (3.76s) === RUN TestRWFileHandleFlushRead run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:11:57 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:11:57 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:11:57 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:57 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:57 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:57 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:11:57 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:11:59.561806 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:11:59.561845 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-xayodap5kapovik2neruhan1'": 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:11:59 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:11:59 DEBUG : : Looking for writers 2024/04/25 05:11:59 DEBUG : >WaitForWriters: 2024/04/25 05:11:59 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleFlushRead (3.79s) === RUN TestRWFileHandleReleaseRead run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:12:01 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:12:01 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:12:01 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:12:01 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:12:01 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:12:01 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:12:01 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:12:03.251968 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:12:03.251993 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-xayodap5kapovik2neruhan1'": 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:12:03 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:12:03 DEBUG : : Looking for writers 2024/04/25 05:12:03 DEBUG : >WaitForWriters: 2024/04/25 05:12:03 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (3.73s) === RUN TestRWFileHandleMethodsWrite run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:12:05 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:12:05 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:12:05 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:12:05 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:12:05 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:12:05 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:12:05 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:12:05 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:12:05 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:12:05 DEBUG : file1: newRWFileHandle: 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): openPending: 2024/04/25 05:12:05 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:12:05 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): >openPending: err= 2024/04/25 05:12:05 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:12:05 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:12:05 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:12:05 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): _writeAt: size=5, off=0 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): >_writeAt: n=5, err= 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): _writeAt: size=7, off=5 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): >_writeAt: n=7, err= 2024/04/25 05:12:05 DEBUG : file1: vfs cache: truncate to size=11 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): close: 2024/04/25 05:12:05 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:12:05.898323927 +0000 UTC m=+177.152765470 2024/04/25 05:12:05 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): >close: err= 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): close: 2024/04/25 05:12:05 DEBUG : file1(0xc0007f2b80): >close: err=file already closed 2024/04/25 05:12:05 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:12:05 DEBUG : : Looking for writers 2024/04/25 05:12:05 DEBUG : file1: reading active writers 2024/04/25 05:12:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:12:05 DEBUG : : Looking for writers 2024/04/25 05:12:05 DEBUG : file1: reading active writers 2024/04/25 05:12:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:12:05 DEBUG : : Looking for writers 2024/04/25 05:12:05 DEBUG : file1: reading active writers 2024/04/25 05:12:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:12:05 DEBUG : : Looking for writers 2024/04/25 05:12:05 DEBUG : file1: reading active writers 2024/04/25 05:12:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:12:05 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:12:06 DEBUG : : Looking for writers 2024/04/25 05:12:06 DEBUG : file1: reading active writers 2024/04/25 05:12:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:12:06 DEBUG : : Looking for writers 2024/04/25 05:12:06 DEBUG : file1: reading active writers 2024/04/25 05:12:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:12:06 DEBUG : : Looking for writers 2024/04/25 05:12:06 DEBUG : file1: reading active writers 2024/04/25 05:12:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:12:06.690353 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:12:06.690397 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:12:06 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:12:06 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:12:06 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:12:07 DEBUG : : Looking for writers 2024/04/25 05:12:07 DEBUG : file1: reading active writers 2024/04/25 05:12:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:07.861758 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:12:07.861785 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:12:08 DEBUG : : Looking for writers 2024/04/25 05:12:08 DEBUG : file1: reading active writers 2024/04/25 05:12:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:09 DEBUG : : Looking for writers 2024/04/25 05:12:09 DEBUG : file1: reading active writers 2024/04/25 05:12:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12: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:12:09 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:12:09 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:12:10 DEBUG : : Looking for writers 2024/04/25 05:12:10 DEBUG : file1: reading active writers 2024/04/25 05:12:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:10.821089 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:12:10.821139 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:12:11 DEBUG : : Looking for writers 2024/04/25 05:12:11 DEBUG : file1: reading active writers 2024/04/25 05:12:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:12 DEBUG : : Looking for writers 2024/04/25 05:12:12 DEBUG : file1: reading active writers 2024/04/25 05:12:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:12 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:12:12 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:12:13 DEBUG : : Looking for writers 2024/04/25 05:12:13 DEBUG : file1: reading active writers 2024/04/25 05:12:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:13 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:12:14.157283 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:12:14.157308 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:12:14 DEBUG : : Looking for writers 2024/04/25 05:12:14 DEBUG : file1: reading active writers 2024/04/25 05:12:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:15 DEBUG : : Looking for writers 2024/04/25 05:12:15 DEBUG : file1: reading active writers 2024/04/25 05:12:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12: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:12:15 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:12:16 DEBUG : : Looking for writers 2024/04/25 05:12:16 DEBUG : file1: reading active writers 2024/04/25 05:12:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:17 DEBUG : : Looking for writers 2024/04/25 05:12:17 DEBUG : file1: reading active writers 2024/04/25 05:12:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:17 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:12:18 DEBUG : : Looking for writers 2024/04/25 05:12:18 DEBUG : file1: reading active writers 2024/04/25 05:12:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:18.355309 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:12:18.355348 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:12:19 DEBUG : : Looking for writers 2024/04/25 05:12:19 DEBUG : file1: reading active writers 2024/04/25 05:12:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:19 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:12:19 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:12:20 DEBUG : : Looking for writers 2024/04/25 05:12:20 DEBUG : file1: reading active writers 2024/04/25 05:12:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:21 DEBUG : : Looking for writers 2024/04/25 05:12:21 DEBUG : file1: reading active writers 2024/04/25 05:12:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:22 DEBUG : : Looking for writers 2024/04/25 05:12:22 DEBUG : file1: reading active writers 2024/04/25 05:12:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:23 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:12:23 DEBUG : : Looking for writers 2024/04/25 05:12:23 DEBUG : file1: reading active writers 2024/04/25 05:12:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:24.144883 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:12:24.144912 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:12:24 DEBUG : : Looking for writers 2024/04/25 05:12:24 DEBUG : file1: reading active writers 2024/04/25 05:12:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:25 DEBUG : : Looking for writers 2024/04/25 05:12:25 DEBUG : file1: reading active writers 2024/04/25 05:12:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12: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:12:25 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:12:26 DEBUG : : Looking for writers 2024/04/25 05:12:26 DEBUG : file1: reading active writers 2024/04/25 05:12:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:27 DEBUG : : Looking for writers 2024/04/25 05:12:27 DEBUG : file1: reading active writers 2024/04/25 05:12:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:28 DEBUG : : Looking for writers 2024/04/25 05:12:28 DEBUG : file1: reading active writers 2024/04/25 05:12:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:29 DEBUG : : Looking for writers 2024/04/25 05:12:29 DEBUG : file1: reading active writers 2024/04/25 05:12:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:30 DEBUG : : Looking for writers 2024/04/25 05:12:30 DEBUG : file1: reading active writers 2024/04/25 05:12:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:31 DEBUG : : Looking for writers 2024/04/25 05:12:31 DEBUG : file1: reading active writers 2024/04/25 05:12:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:32 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:12:32 DEBUG : : Looking for writers 2024/04/25 05:12:32 DEBUG : file1: reading active writers 2024/04/25 05:12:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:33.063816 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:12:33.063850 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:12:33 DEBUG : : Looking for writers 2024/04/25 05:12:33 DEBUG : file1: reading active writers 2024/04/25 05:12:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:34 DEBUG : : Looking for writers 2024/04/25 05:12:34 DEBUG : file1: reading active writers 2024/04/25 05:12:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:34 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:12:34 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:12:35 DEBUG : : Looking for writers 2024/04/25 05:12:35 DEBUG : file1: reading active writers 2024/04/25 05:12:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:12:35 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc000a64690 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc00079a408 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641692736} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943873875491902935 ext:177152765470 loc:0x3a135e0} ATime:{wall:13943873875491947628 ext:177152810174 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:12:35 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:12:47 DEBUG : file1: vfs cache: starting upload fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 2024/04/25 05:12:54.679465 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:12:54.679496 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:295: Flushing the directory cache 2024/04/25 05:12:56 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:12:56 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 2024/04/25 05:13:05 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:13:05 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:13:16 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:13:16 DEBUG : : Looking for writers 2024/04/25 05:13:16 DEBUG : file1: reading active writers 2024/04/25 05:13:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:13:16 DEBUG : : Looking for writers 2024/04/25 05:13:16 DEBUG : file1: reading active writers 2024/04/25 05:13:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:13:16 DEBUG : : Looking for writers 2024/04/25 05:13:16 DEBUG : file1: reading active writers 2024/04/25 05:13:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:13:16 DEBUG : : Looking for writers 2024/04/25 05:13:16 DEBUG : file1: reading active writers 2024/04/25 05:13:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:13:17 DEBUG : : Looking for writers 2024/04/25 05:13:17 DEBUG : file1: reading active writers 2024/04/25 05:13:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:13:17 DEBUG : : Looking for writers 2024/04/25 05:13:17 DEBUG : file1: reading active writers 2024/04/25 05:13:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:13:17 DEBUG : : Looking for writers 2024/04/25 05:13:17 DEBUG : file1: reading active writers 2024/04/25 05:13:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:13:18 DEBUG : : Looking for writers 2024/04/25 05:13:18 DEBUG : file1: reading active writers 2024/04/25 05:13:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:19 DEBUG : : Looking for writers 2024/04/25 05:13:19 DEBUG : file1: reading active writers 2024/04/25 05:13:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:20 DEBUG : : Looking for writers 2024/04/25 05:13:20 DEBUG : file1: reading active writers 2024/04/25 05:13:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:21 DEBUG : : Looking for writers 2024/04/25 05:13:21 DEBUG : file1: reading active writers 2024/04/25 05:13:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:21 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:13:22 DEBUG : : Looking for writers 2024/04/25 05:13:22 DEBUG : file1: reading active writers 2024/04/25 05:13:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:23 DEBUG : : Looking for writers 2024/04/25 05:13:23 DEBUG : file1: reading active writers 2024/04/25 05:13:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:24 DEBUG : : Looking for writers 2024/04/25 05:13:24 DEBUG : file1: reading active writers 2024/04/25 05:13:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:25 DEBUG : : Looking for writers 2024/04/25 05:13:25 DEBUG : file1: reading active writers 2024/04/25 05:13:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:26 DEBUG : : Looking for writers 2024/04/25 05:13:26 DEBUG : file1: reading active writers 2024/04/25 05:13:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:27 DEBUG : : Looking for writers 2024/04/25 05:13:27 DEBUG : file1: reading active writers 2024/04/25 05:13:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:28 DEBUG : : Looking for writers 2024/04/25 05:13:28 DEBUG : file1: reading active writers 2024/04/25 05:13:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:29 DEBUG : : Looking for writers 2024/04/25 05:13:29 DEBUG : file1: reading active writers 2024/04/25 05:13:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:30 DEBUG : : Looking for writers 2024/04/25 05:13:30 DEBUG : file1: reading active writers 2024/04/25 05:13:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:31 DEBUG : : Looking for writers 2024/04/25 05:13:31 DEBUG : file1: reading active writers 2024/04/25 05:13:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:32 DEBUG : : Looking for writers 2024/04/25 05:13:32 DEBUG : file1: reading active writers 2024/04/25 05:13:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:33 DEBUG : : Looking for writers 2024/04/25 05:13:33 DEBUG : file1: reading active writers 2024/04/25 05:13:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:34 DEBUG : : Looking for writers 2024/04/25 05:13:34 DEBUG : file1: reading active writers 2024/04/25 05:13:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:35 DEBUG : : Looking for writers 2024/04/25 05:13:35 DEBUG : file1: reading active writers 2024/04/25 05:13:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:36 DEBUG : : Looking for writers 2024/04/25 05:13:36 DEBUG : file1: reading active writers 2024/04/25 05:13:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:37 DEBUG : : Looking for writers 2024/04/25 05:13:37 DEBUG : file1: reading active writers 2024/04/25 05:13:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:38 DEBUG : : Looking for writers 2024/04/25 05:13:38 DEBUG : file1: reading active writers 2024/04/25 05:13:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:39.120628 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:13:39.120659 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:13:39 DEBUG : : Looking for writers 2024/04/25 05:13:39 DEBUG : file1: reading active writers 2024/04/25 05:13:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:40 DEBUG : : Looking for writers 2024/04/25 05:13:40 DEBUG : file1: reading active writers 2024/04/25 05:13:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:40 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:13:40 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:13:41 DEBUG : : Looking for writers 2024/04/25 05:13:41 DEBUG : file1: reading active writers 2024/04/25 05:13:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:42 DEBUG : : Looking for writers 2024/04/25 05:13:42 DEBUG : file1: reading active writers 2024/04/25 05:13:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:43 DEBUG : : Looking for writers 2024/04/25 05:13:43 DEBUG : file1: reading active writers 2024/04/25 05:13:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:44 DEBUG : : Looking for writers 2024/04/25 05:13:44 DEBUG : file1: reading active writers 2024/04/25 05:13:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:45 DEBUG : : Looking for writers 2024/04/25 05:13:45 DEBUG : file1: reading active writers 2024/04/25 05:13:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:46 DEBUG : : Looking for writers 2024/04/25 05:13:46 DEBUG : file1: reading active writers 2024/04/25 05:13:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:46 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc000a64690 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc00079a408 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641692736} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943873875491902935 ext:177152765470 loc:0x3a135e0} ATime:{wall:13943873875491947628 ext:177152810174 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:13:46 DEBUG : >WaitForWriters: 2024/04/25 05:13:46 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsWrite (102.44s) === RUN TestRWFileHandleWriteAt run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:13:47 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:13:47 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:13:47 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:13:47 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:13:47 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:13:47 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:13:47 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:13:47 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:13:48 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:13:48 DEBUG : file1: newRWFileHandle: 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): openPending: 2024/04/25 05:13:48 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:13:48 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): >openPending: err= 2024/04/25 05:13:48 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:13:48 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:13:48 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:13:48 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): _writeAt: size=7, off=0 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): >_writeAt: n=7, err= 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): _writeAt: size=6, off=5 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): >_writeAt: n=6, err= 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): close: 2024/04/25 05:13:48 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:13:48.2644096 +0000 UTC m=+279.518851114 2024/04/25 05:13:48 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): >close: err= 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): _writeAt: size=5, off=0 2024/04/25 05:13:48 DEBUG : file1(0xc000bbeac0): >_writeAt: n=0, err=file already closed 2024/04/25 05:13:48 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:13:48 DEBUG : : Looking for writers 2024/04/25 05:13:48 DEBUG : file1: reading active writers 2024/04/25 05:13:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:13:48 DEBUG : : Looking for writers 2024/04/25 05:13:48 DEBUG : file1: reading active writers 2024/04/25 05:13:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:13:48 DEBUG : : Looking for writers 2024/04/25 05:13:48 DEBUG : file1: reading active writers 2024/04/25 05:13:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:13:48 DEBUG : : Looking for writers 2024/04/25 05:13:48 DEBUG : file1: reading active writers 2024/04/25 05:13:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:13:48 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:13:48 DEBUG : : Looking for writers 2024/04/25 05:13:48 DEBUG : file1: reading active writers 2024/04/25 05:13:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:13:48 DEBUG : : Looking for writers 2024/04/25 05:13:48 DEBUG : file1: reading active writers 2024/04/25 05:13:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:13:48 DEBUG : : Looking for writers 2024/04/25 05:13:48 DEBUG : file1: reading active writers 2024/04/25 05:13:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:13:49.342330 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:13:49.342356 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:13:49 DEBUG : : Looking for writers 2024/04/25 05:13:49 DEBUG : file1: reading active writers 2024/04/25 05:13:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:50 DEBUG : : Looking for writers 2024/04/25 05:13:50 DEBUG : file1: reading active writers 2024/04/25 05:13:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13: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:13:50 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:13:51 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:13:51 DEBUG : : Looking for writers 2024/04/25 05:13:51 DEBUG : file1: reading active writers 2024/04/25 05:13:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:52.103715 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:13:52.103743 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:13:52 DEBUG : : Looking for writers 2024/04/25 05:13:52 DEBUG : file1: reading active writers 2024/04/25 05:13:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:53 DEBUG : : Looking for writers 2024/04/25 05:13:53 DEBUG : file1: reading active writers 2024/04/25 05:13:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:53 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:13:53 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:13:54 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:13:54 DEBUG : : Looking for writers 2024/04/25 05:13:54 DEBUG : file1: reading active writers 2024/04/25 05:13:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:55.008958 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:13:55.008989 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:13:55 DEBUG : : Looking for writers 2024/04/25 05:13:55 DEBUG : file1: reading active writers 2024/04/25 05:13:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:56 DEBUG : : Looking for writers 2024/04/25 05:13:56 DEBUG : file1: reading active writers 2024/04/25 05:13:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:56 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:13:56 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:13:57 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:13:57 DEBUG : : Looking for writers 2024/04/25 05:13:57 DEBUG : file1: reading active writers 2024/04/25 05:13:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:58.411640 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:13:58.411665 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:13:58 DEBUG : : Looking for writers 2024/04/25 05:13:58 DEBUG : file1: reading active writers 2024/04/25 05:13:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13:59 DEBUG : : Looking for writers 2024/04/25 05:13:59 DEBUG : file1: reading active writers 2024/04/25 05:13:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:13: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:13:59 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:14:00 DEBUG : : Looking for writers 2024/04/25 05:14:00 DEBUG : file1: reading active writers 2024/04/25 05:14:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:01 DEBUG : : Looking for writers 2024/04/25 05:14:01 DEBUG : file1: reading active writers 2024/04/25 05:14:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:01 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:14:02.527434 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:14:02.527462 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:14:02 DEBUG : : Looking for writers 2024/04/25 05:14:02 DEBUG : file1: reading active writers 2024/04/25 05:14:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:03 DEBUG : : Looking for writers 2024/04/25 05:14:03 DEBUG : file1: reading active writers 2024/04/25 05:14:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14: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:14:04 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:14:04 DEBUG : : Looking for writers 2024/04/25 05:14:04 DEBUG : file1: reading active writers 2024/04/25 05:14:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:05 DEBUG : : Looking for writers 2024/04/25 05:14:05 DEBUG : file1: reading active writers 2024/04/25 05:14:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:06 DEBUG : : Looking for writers 2024/04/25 05:14:06 DEBUG : file1: reading active writers 2024/04/25 05:14:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:07 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:14:07 DEBUG : : Looking for writers 2024/04/25 05:14:07 DEBUG : file1: reading active writers 2024/04/25 05:14:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:08.241825 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:14:08.241849 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:14:08 DEBUG : : Looking for writers 2024/04/25 05:14:08 DEBUG : file1: reading active writers 2024/04/25 05:14:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:09 DEBUG : : Looking for writers 2024/04/25 05:14:09 DEBUG : file1: reading active writers 2024/04/25 05:14:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14: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:14:09 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:14:10 DEBUG : : Looking for writers 2024/04/25 05:14:10 DEBUG : file1: reading active writers 2024/04/25 05:14:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:11 DEBUG : : Looking for writers 2024/04/25 05:14:11 DEBUG : file1: reading active writers 2024/04/25 05:14:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:12 DEBUG : : Looking for writers 2024/04/25 05:14:12 DEBUG : file1: reading active writers 2024/04/25 05:14:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:13 DEBUG : : Looking for writers 2024/04/25 05:14:13 DEBUG : file1: reading active writers 2024/04/25 05:14:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:14 DEBUG : : Looking for writers 2024/04/25 05:14:14 DEBUG : file1: reading active writers 2024/04/25 05:14:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:15 DEBUG : : Looking for writers 2024/04/25 05:14:15 DEBUG : file1: reading active writers 2024/04/25 05:14:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:16 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:14:16 DEBUG : : Looking for writers 2024/04/25 05:14:16 DEBUG : file1: reading active writers 2024/04/25 05:14:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:17.202244 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:14:17.202267 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:14:17 DEBUG : : Looking for writers 2024/04/25 05:14:17 DEBUG : file1: reading active writers 2024/04/25 05:14:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:14:18 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc00063ab40 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000726d08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641219904} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943873985453396480 ext:279518851114 loc:0x3a135e0} ATime:{wall:13943873985453422219 ext:279518876851 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:14:18 DEBUG : >WaitForWriters: fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 2024/04/25 05:14:18 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:14:18 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 fstest.go:295: Flushing the directory cache 2024/04/25 05:14:31 DEBUG : file1: vfs cache: starting upload fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 2024/04/25 05:14:37.128543 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:14:37.128579 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:295: Flushing the directory cache 2024/04/25 05:14: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:14:38 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 2024/04/25 05:14:47 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:14:47 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:14:59 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:14:59 DEBUG : : Looking for writers 2024/04/25 05:14:59 DEBUG : file1: reading active writers 2024/04/25 05:14:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:14:59 DEBUG : : Looking for writers 2024/04/25 05:14:59 DEBUG : file1: reading active writers 2024/04/25 05:14:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:14:59 DEBUG : : Looking for writers 2024/04/25 05:14:59 DEBUG : file1: reading active writers 2024/04/25 05:14:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:14:59 DEBUG : : Looking for writers 2024/04/25 05:14:59 DEBUG : file1: reading active writers 2024/04/25 05:14:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:14:59 DEBUG : : Looking for writers 2024/04/25 05:14:59 DEBUG : file1: reading active writers 2024/04/25 05:14:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:14:59 DEBUG : : Looking for writers 2024/04/25 05:14:59 DEBUG : file1: reading active writers 2024/04/25 05:14:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:14:59 DEBUG : : Looking for writers 2024/04/25 05:14:59 DEBUG : file1: reading active writers 2024/04/25 05:14:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:15:00 DEBUG : : Looking for writers 2024/04/25 05:15:00 DEBUG : file1: reading active writers 2024/04/25 05:15:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:01 DEBUG : : Looking for writers 2024/04/25 05:15:01 DEBUG : file1: reading active writers 2024/04/25 05:15:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:02 DEBUG : : Looking for writers 2024/04/25 05:15:02 DEBUG : file1: reading active writers 2024/04/25 05:15:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:03 DEBUG : : Looking for writers 2024/04/25 05:15:03 DEBUG : file1: reading active writers 2024/04/25 05:15:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:04 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:15:04 DEBUG : : Looking for writers 2024/04/25 05:15:04 DEBUG : file1: reading active writers 2024/04/25 05:15:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:05 DEBUG : : Looking for writers 2024/04/25 05:15:05 DEBUG : file1: reading active writers 2024/04/25 05:15:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:06 DEBUG : : Looking for writers 2024/04/25 05:15:06 DEBUG : file1: reading active writers 2024/04/25 05:15:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:07 DEBUG : : Looking for writers 2024/04/25 05:15:07 DEBUG : file1: reading active writers 2024/04/25 05:15:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:08 DEBUG : : Looking for writers 2024/04/25 05:15:08 DEBUG : file1: reading active writers 2024/04/25 05:15:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:09 DEBUG : : Looking for writers 2024/04/25 05:15:09 DEBUG : file1: reading active writers 2024/04/25 05:15:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:10 DEBUG : : Looking for writers 2024/04/25 05:15:10 DEBUG : file1: reading active writers 2024/04/25 05:15:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:11 DEBUG : : Looking for writers 2024/04/25 05:15:11 DEBUG : file1: reading active writers 2024/04/25 05:15:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:12 DEBUG : : Looking for writers 2024/04/25 05:15:12 DEBUG : file1: reading active writers 2024/04/25 05:15:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:13 DEBUG : : Looking for writers 2024/04/25 05:15:13 DEBUG : file1: reading active writers 2024/04/25 05:15:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:14 DEBUG : : Looking for writers 2024/04/25 05:15:14 DEBUG : file1: reading active writers 2024/04/25 05:15:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:15 DEBUG : : Looking for writers 2024/04/25 05:15:15 DEBUG : file1: reading active writers 2024/04/25 05:15:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:16 DEBUG : : Looking for writers 2024/04/25 05:15:16 DEBUG : file1: reading active writers 2024/04/25 05:15:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:17 DEBUG : : Looking for writers 2024/04/25 05:15:17 DEBUG : file1: reading active writers 2024/04/25 05:15:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:18 DEBUG : : Looking for writers 2024/04/25 05:15:18 DEBUG : file1: reading active writers 2024/04/25 05:15:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:19 DEBUG : : Looking for writers 2024/04/25 05:15:19 DEBUG : file1: reading active writers 2024/04/25 05:15:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:20 DEBUG : : Looking for writers 2024/04/25 05:15:20 DEBUG : file1: reading active writers 2024/04/25 05:15:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:21 DEBUG : : Looking for writers 2024/04/25 05:15:21 DEBUG : file1: reading active writers 2024/04/25 05:15:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:21.515724 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:15:21.515753 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:15:22 DEBUG : : Looking for writers 2024/04/25 05:15:22 DEBUG : file1: reading active writers 2024/04/25 05:15:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15: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:15:23 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:15:23 DEBUG : : Looking for writers 2024/04/25 05:15:23 DEBUG : file1: reading active writers 2024/04/25 05:15:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:24 DEBUG : : Looking for writers 2024/04/25 05:15:24 DEBUG : file1: reading active writers 2024/04/25 05:15:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:25 DEBUG : : Looking for writers 2024/04/25 05:15:25 DEBUG : file1: reading active writers 2024/04/25 05:15:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:26 DEBUG : : Looking for writers 2024/04/25 05:15:26 DEBUG : file1: reading active writers 2024/04/25 05:15:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:27 DEBUG : : Looking for writers 2024/04/25 05:15:27 DEBUG : file1: reading active writers 2024/04/25 05:15:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:28 DEBUG : : Looking for writers 2024/04/25 05:15:28 DEBUG : file1: reading active writers 2024/04/25 05:15:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:29 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc00063ab40 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000726d08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641219904} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943873985453396480 ext:279518851114 loc:0x3a135e0} ATime:{wall:13943873985453422219 ext:279518876851 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:15:29 DEBUG : >WaitForWriters: 2024/04/25 05:15:29 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteAt (102.15s) === RUN TestRWFileHandleWriteNoWrite run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:15:29 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:15:29 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:15:29 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:15:29 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:15:29 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:15:29 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:15:29 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:15:29 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:15:30 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:15:30 DEBUG : file1: newRWFileHandle: 2024/04/25 05:15:30 DEBUG : file1(0xc000849500): openPending: 2024/04/25 05:15:30 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:15:30 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:15:30 DEBUG : file1(0xc000849500): >openPending: err= 2024/04/25 05:15:30 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:15:30 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:15:30 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:15:30 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:15:30 DEBUG : file1(0xc000849500): close: 2024/04/25 05:15:30 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:15:30.411955973 +0000 UTC m=+381.666397516 2024/04/25 05:15:30 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:15:30 DEBUG : file1(0xc000849500): >close: err= 2024/04/25 05:15:30 DEBUG : file2: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2024/04/25 05:15:30 DEBUG : file2: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2024/04/25 05:15:30 DEBUG : file2: newRWFileHandle: 2024/04/25 05:15:30 DEBUG : file2(0xc000849600): openPending: 2024/04/25 05:15:30 DEBUG : file2: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:15:30 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:15:30 DEBUG : file2(0xc000849600): >openPending: err= 2024/04/25 05:15:30 DEBUG : file2: >newRWFileHandle: err= 2024/04/25 05:15:30 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:15:30 DEBUG : file2: >Open: fd=file2 (rw), err= 2024/04/25 05:15:30 DEBUG : file2: >OpenFile: fd=file2 (rw), err= 2024/04/25 05:15:30 DEBUG : file2(0xc000849600): RWFileHandle.Flush 2024/04/25 05:15:30 DEBUG : file2(0xc000849600): RWFileHandle.Release 2024/04/25 05:15:30 DEBUG : file2(0xc000849600): close: 2024/04/25 05:15:30 DEBUG : file2: vfs cache: setting modification time to 2024-04-25 05:15:30.413171031 +0000 UTC m=+381.667612574 2024/04/25 05:15:30 INFO : file2: vfs cache: queuing for upload in 100ms 2024/04/25 05:15:30 DEBUG : file2(0xc000849600): >close: err= 2024/04/25 05:15:30 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:15:30 DEBUG : : Looking for writers 2024/04/25 05:15:30 DEBUG : file1: reading active writers 2024/04/25 05:15:30 DEBUG : file2: reading active writers 2024/04/25 05:15:30 DEBUG : Still 0 writers active and 2 cache items in use, waiting 10ms 2024/04/25 05:15:30 DEBUG : : Looking for writers 2024/04/25 05:15:30 DEBUG : file1: reading active writers 2024/04/25 05:15:30 DEBUG : file2: reading active writers 2024/04/25 05:15:30 DEBUG : Still 0 writers active and 2 cache items in use, waiting 20ms 2024/04/25 05:15:30 DEBUG : : Looking for writers 2024/04/25 05:15:30 DEBUG : file1: reading active writers 2024/04/25 05:15:30 DEBUG : file2: reading active writers 2024/04/25 05:15:30 DEBUG : Still 0 writers active and 2 cache items in use, waiting 40ms 2024/04/25 05:15:30 DEBUG : : Looking for writers 2024/04/25 05:15:30 DEBUG : file1: reading active writers 2024/04/25 05:15:30 DEBUG : file2: reading active writers 2024/04/25 05:15:30 DEBUG : Still 0 writers active and 2 cache items in use, waiting 80ms 2024/04/25 05:15:30 DEBUG : file2: vfs cache: starting upload 2024/04/25 05:15:30 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:15:30 DEBUG : : Looking for writers 2024/04/25 05:15:30 DEBUG : file1: reading active writers 2024/04/25 05:15:30 DEBUG : file2: reading active writers 2024/04/25 05:15:30 DEBUG : Still 0 writers active and 2 cache items in use, waiting 160ms 2024/04/25 05:15:30 DEBUG : : Looking for writers 2024/04/25 05:15:30 DEBUG : file1: reading active writers 2024/04/25 05:15:30 DEBUG : file2: reading active writers 2024/04/25 05:15:30 DEBUG : Still 0 writers active and 2 cache items in use, waiting 320ms 2024/04/25 05:15:31 DEBUG : : Looking for writers 2024/04/25 05:15:31 DEBUG : file2: reading active writers 2024/04/25 05:15:31 DEBUG : file1: reading active writers 2024/04/25 05:15:31 DEBUG : Still 0 writers active and 2 cache items in use, waiting 640ms 2024/04/25 05:15:31 DEBUG : file2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/04/25 05:15:31 INFO : file2: Copied (new) 2024/04/25 05:15:31 DEBUG : file2: vfs cache: fingerprint now "0,2024-04-25 05:15:30.413171031 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709" 2024/04/25 05:15:31 DEBUG : file2: vfs cache: writeback object to VFS layer 2024/04/25 05:15:31 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:15:31 INFO : file2: vfs cache: upload succeeded try #1 2024/04/25 05:15:31.458850 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:15:31.458884 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:15:31 DEBUG : : Looking for writers 2024/04/25 05:15:31 DEBUG : file1: reading active writers 2024/04/25 05:15:31 DEBUG : file2: reading active writers 2024/04/25 05:15:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:32 DEBUG : : Looking for writers 2024/04/25 05:15:32 DEBUG : file1: reading active writers 2024/04/25 05:15:32 DEBUG : file2: reading active writers 2024/04/25 05:15:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15: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:15:33 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:15:33 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:15:33 DEBUG : : Looking for writers 2024/04/25 05:15:33 DEBUG : file2: reading active writers 2024/04/25 05:15:33 DEBUG : file1: reading active writers 2024/04/25 05:15:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:34.243389 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:15:34.243422 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:15:34 DEBUG : : Looking for writers 2024/04/25 05:15:34 DEBUG : file1: reading active writers 2024/04/25 05:15:34 DEBUG : file2: reading active writers 2024/04/25 05:15:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:35 DEBUG : : Looking for writers 2024/04/25 05:15:35 DEBUG : file1: reading active writers 2024/04/25 05:15:35 DEBUG : file2: reading active writers 2024/04/25 05:15:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:35 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:15:35 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:15:36 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:15:36 DEBUG : : Looking for writers 2024/04/25 05:15:36 DEBUG : file1: reading active writers 2024/04/25 05:15:36 DEBUG : file2: reading active writers 2024/04/25 05:15:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:37.198514 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:15:37.198544 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:15:37 DEBUG : : Looking for writers 2024/04/25 05:15:37 DEBUG : file1: reading active writers 2024/04/25 05:15:37 DEBUG : file2: reading active writers 2024/04/25 05:15:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:38 DEBUG : : Looking for writers 2024/04/25 05:15:38 DEBUG : file1: reading active writers 2024/04/25 05:15:38 DEBUG : file2: reading active writers 2024/04/25 05:15:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15: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:15:38 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:15:39 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:15:39 DEBUG : : Looking for writers 2024/04/25 05:15:39 DEBUG : file1: reading active writers 2024/04/25 05:15:39 DEBUG : file2: reading active writers 2024/04/25 05:15:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:40.554183 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:15:40.554218 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:15:40 DEBUG : : Looking for writers 2024/04/25 05:15:40 DEBUG : file2: reading active writers 2024/04/25 05:15:40 DEBUG : file1: reading active writers 2024/04/25 05:15:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:41 DEBUG : : Looking for writers 2024/04/25 05:15:41 DEBUG : file2: reading active writers 2024/04/25 05:15:41 DEBUG : file1: reading active writers 2024/04/25 05:15:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:42 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:15:42 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:15:42 DEBUG : : Looking for writers 2024/04/25 05:15:42 DEBUG : file1: reading active writers 2024/04/25 05:15:42 DEBUG : file2: reading active writers 2024/04/25 05:15:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:43 DEBUG : : Looking for writers 2024/04/25 05:15:43 DEBUG : file1: reading active writers 2024/04/25 05:15:43 DEBUG : file2: reading active writers 2024/04/25 05:15:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:43 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:15:44 DEBUG : : Looking for writers 2024/04/25 05:15:44 DEBUG : file1: reading active writers 2024/04/25 05:15:44 DEBUG : file2: reading active writers 2024/04/25 05:15:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:44.693523 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:15:44.693544 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:15:45 DEBUG : : Looking for writers 2024/04/25 05:15:45 DEBUG : file1: reading active writers 2024/04/25 05:15:45 DEBUG : file2: reading active writers 2024/04/25 05:15:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15: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:15:46 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:15:46 DEBUG : : Looking for writers 2024/04/25 05:15:46 DEBUG : file1: reading active writers 2024/04/25 05:15:46 DEBUG : file2: reading active writers 2024/04/25 05:15:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:47 DEBUG : : Looking for writers 2024/04/25 05:15:47 DEBUG : file1: reading active writers 2024/04/25 05:15:47 DEBUG : file2: reading active writers 2024/04/25 05:15:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:48 DEBUG : : Looking for writers 2024/04/25 05:15:48 DEBUG : file1: reading active writers 2024/04/25 05:15:48 DEBUG : file2: reading active writers 2024/04/25 05:15:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:49 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:15:49 DEBUG : : Looking for writers 2024/04/25 05:15:49 DEBUG : file1: reading active writers 2024/04/25 05:15:49 DEBUG : file2: reading active writers 2024/04/25 05:15:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:50.498515 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:15:50.498552 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:15:50 DEBUG : : Looking for writers 2024/04/25 05:15:50 DEBUG : file1: reading active writers 2024/04/25 05:15:50 DEBUG : file2: reading active writers 2024/04/25 05:15:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:51 DEBUG : : Looking for writers 2024/04/25 05:15:51 DEBUG : file1: reading active writers 2024/04/25 05:15:51 DEBUG : file2: reading active writers 2024/04/25 05:15:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15: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:15: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:15:52 DEBUG : : Looking for writers 2024/04/25 05:15:52 DEBUG : file1: reading active writers 2024/04/25 05:15:52 DEBUG : file2: reading active writers 2024/04/25 05:15:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:53 DEBUG : : Looking for writers 2024/04/25 05:15:53 DEBUG : file1: reading active writers 2024/04/25 05:15:53 DEBUG : file2: reading active writers 2024/04/25 05:15:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:54 DEBUG : : Looking for writers 2024/04/25 05:15:54 DEBUG : file1: reading active writers 2024/04/25 05:15:54 DEBUG : file2: reading active writers 2024/04/25 05:15:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:55 DEBUG : : Looking for writers 2024/04/25 05:15:55 DEBUG : file1: reading active writers 2024/04/25 05:15:55 DEBUG : file2: reading active writers 2024/04/25 05:15:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:56 DEBUG : : Looking for writers 2024/04/25 05:15:56 DEBUG : file1: reading active writers 2024/04/25 05:15:56 DEBUG : file2: reading active writers 2024/04/25 05:15:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:57 DEBUG : : Looking for writers 2024/04/25 05:15:57 DEBUG : file1: reading active writers 2024/04/25 05:15:57 DEBUG : file2: reading active writers 2024/04/25 05:15:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:58 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:15:58 DEBUG : : Looking for writers 2024/04/25 05:15:58 DEBUG : file1: reading active writers 2024/04/25 05:15:58 DEBUG : file2: reading active writers 2024/04/25 05:15:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:15:59.584849 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:15:59.584881 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:15:59 DEBUG : : Looking for writers 2024/04/25 05:15:59 DEBUG : file2: reading active writers 2024/04/25 05:15:59 DEBUG : file1: reading active writers 2024/04/25 05:15:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:00 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc000a64870 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000727208 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641221184} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943874095122608901 ext:381666397516 loc:0x3a135e0} ATime:{wall:13943874095122877735 ext:381666666350 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, "file2": &{c:0xc000a64870 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000727308 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641221440} name:file2 opens:0 downloaders: o:0xc0007ef180 fd: info:{ModTime:{wall:13943874095123823959 ext:381667612574 loc:0x3a135e0} ATime:{wall:13943874095124001082 ext:381667789697 loc:0x3a135e0} Size:0 Rs:[] Fingerprint:0,2024-04-25 05:15:30.413171031 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709 Dirty:false} writeBackID:2 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:16:00 DEBUG : >WaitForWriters: 2024/04/25 05:16:01 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:16:01 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 fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/04/25 05:16:13 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:16:19.937852 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:16:19.937883 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:16:21 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:16:21 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:16:29 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file2 not removed, freed 0 bytes 2024/04/25 05:16:29 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:16:29 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:16:43 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:16:43 DEBUG : : Looking for writers 2024/04/25 05:16:43 DEBUG : file2: reading active writers 2024/04/25 05:16:43 DEBUG : file1: reading active writers 2024/04/25 05:16:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:16:44 DEBUG : : Looking for writers 2024/04/25 05:16:44 DEBUG : file1: reading active writers 2024/04/25 05:16:44 DEBUG : file2: reading active writers 2024/04/25 05:16:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:16:44 DEBUG : : Looking for writers 2024/04/25 05:16:44 DEBUG : file1: reading active writers 2024/04/25 05:16:44 DEBUG : file2: reading active writers 2024/04/25 05:16:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:16:44 DEBUG : : Looking for writers 2024/04/25 05:16:44 DEBUG : file1: reading active writers 2024/04/25 05:16:44 DEBUG : file2: reading active writers 2024/04/25 05:16:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:16:44 DEBUG : : Looking for writers 2024/04/25 05:16:44 DEBUG : file1: reading active writers 2024/04/25 05:16:44 DEBUG : file2: reading active writers 2024/04/25 05:16:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:16:44 DEBUG : : Looking for writers 2024/04/25 05:16:44 DEBUG : file1: reading active writers 2024/04/25 05:16:44 DEBUG : file2: reading active writers 2024/04/25 05:16:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:16:44 DEBUG : : Looking for writers 2024/04/25 05:16:44 DEBUG : file1: reading active writers 2024/04/25 05:16:44 DEBUG : file2: reading active writers 2024/04/25 05:16:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:16:45 DEBUG : : Looking for writers 2024/04/25 05:16:45 DEBUG : file1: reading active writers 2024/04/25 05:16:45 DEBUG : file2: reading active writers 2024/04/25 05:16:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:46 DEBUG : : Looking for writers 2024/04/25 05:16:46 DEBUG : file1: reading active writers 2024/04/25 05:16:46 DEBUG : file2: reading active writers 2024/04/25 05:16:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:47 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:16:47 DEBUG : : Looking for writers 2024/04/25 05:16:47 DEBUG : file1: reading active writers 2024/04/25 05:16:47 DEBUG : file2: reading active writers 2024/04/25 05:16:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:48 DEBUG : : Looking for writers 2024/04/25 05:16:48 DEBUG : file1: reading active writers 2024/04/25 05:16:48 DEBUG : file2: reading active writers 2024/04/25 05:16:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:49 DEBUG : : Looking for writers 2024/04/25 05:16:49 DEBUG : file2: reading active writers 2024/04/25 05:16:49 DEBUG : file1: reading active writers 2024/04/25 05:16:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:50 DEBUG : : Looking for writers 2024/04/25 05:16:50 DEBUG : file1: reading active writers 2024/04/25 05:16:50 DEBUG : file2: reading active writers 2024/04/25 05:16:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:51 DEBUG : : Looking for writers 2024/04/25 05:16:51 DEBUG : file1: reading active writers 2024/04/25 05:16:51 DEBUG : file2: reading active writers 2024/04/25 05:16:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:52 DEBUG : : Looking for writers 2024/04/25 05:16:52 DEBUG : file1: reading active writers 2024/04/25 05:16:52 DEBUG : file2: reading active writers 2024/04/25 05:16:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:53 DEBUG : : Looking for writers 2024/04/25 05:16:53 DEBUG : file1: reading active writers 2024/04/25 05:16:53 DEBUG : file2: reading active writers 2024/04/25 05:16:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:54 DEBUG : : Looking for writers 2024/04/25 05:16:54 DEBUG : file2: reading active writers 2024/04/25 05:16:54 DEBUG : file1: reading active writers 2024/04/25 05:16:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:55 DEBUG : : Looking for writers 2024/04/25 05:16:55 DEBUG : file2: reading active writers 2024/04/25 05:16:55 DEBUG : file1: reading active writers 2024/04/25 05:16:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:56 DEBUG : : Looking for writers 2024/04/25 05:16:56 DEBUG : file2: reading active writers 2024/04/25 05:16:56 DEBUG : file1: reading active writers 2024/04/25 05:16:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:57 DEBUG : : Looking for writers 2024/04/25 05:16:57 DEBUG : file1: reading active writers 2024/04/25 05:16:57 DEBUG : file2: reading active writers 2024/04/25 05:16:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:58 DEBUG : : Looking for writers 2024/04/25 05:16:58 DEBUG : file1: reading active writers 2024/04/25 05:16:58 DEBUG : file2: reading active writers 2024/04/25 05:16:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:16:59 DEBUG : : Looking for writers 2024/04/25 05:16:59 DEBUG : file1: reading active writers 2024/04/25 05:16:59 DEBUG : file2: reading active writers 2024/04/25 05:16:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:00 DEBUG : : Looking for writers 2024/04/25 05:17:00 DEBUG : file2: reading active writers 2024/04/25 05:17:00 DEBUG : file1: reading active writers 2024/04/25 05:17:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:01 DEBUG : : Looking for writers 2024/04/25 05:17:01 DEBUG : file1: reading active writers 2024/04/25 05:17:01 DEBUG : file2: reading active writers 2024/04/25 05:17:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:02 DEBUG : : Looking for writers 2024/04/25 05:17:02 DEBUG : file1: reading active writers 2024/04/25 05:17:02 DEBUG : file2: reading active writers 2024/04/25 05:17:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:03 DEBUG : : Looking for writers 2024/04/25 05:17:03 DEBUG : file1: reading active writers 2024/04/25 05:17:03 DEBUG : file2: reading active writers 2024/04/25 05:17:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:04 DEBUG : : Looking for writers 2024/04/25 05:17:04 DEBUG : file1: reading active writers 2024/04/25 05:17:04 DEBUG : file2: reading active writers 2024/04/25 05:17:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:04.325159 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:17:04.325196 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:17:05 DEBUG : : Looking for writers 2024/04/25 05:17:05 DEBUG : file1: reading active writers 2024/04/25 05:17:05 DEBUG : file2: reading active writers 2024/04/25 05:17:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17: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:17: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:17:06 DEBUG : : Looking for writers 2024/04/25 05:17:06 DEBUG : file2: reading active writers 2024/04/25 05:17:06 DEBUG : file1: reading active writers 2024/04/25 05:17:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:07 DEBUG : : Looking for writers 2024/04/25 05:17:07 DEBUG : file1: reading active writers 2024/04/25 05:17:07 DEBUG : file2: reading active writers 2024/04/25 05:17:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:08 DEBUG : : Looking for writers 2024/04/25 05:17:08 DEBUG : file1: reading active writers 2024/04/25 05:17:08 DEBUG : file2: reading active writers 2024/04/25 05:17:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:09 DEBUG : : Looking for writers 2024/04/25 05:17:09 DEBUG : file1: reading active writers 2024/04/25 05:17:09 DEBUG : file2: reading active writers 2024/04/25 05:17:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:10 DEBUG : : Looking for writers 2024/04/25 05:17:10 DEBUG : file1: reading active writers 2024/04/25 05:17:10 DEBUG : file2: reading active writers 2024/04/25 05:17:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:11 DEBUG : : Looking for writers 2024/04/25 05:17:11 DEBUG : file2: reading active writers 2024/04/25 05:17:11 DEBUG : file1: reading active writers 2024/04/25 05:17:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:12 DEBUG : : Looking for writers 2024/04/25 05:17:12 DEBUG : file1: reading active writers 2024/04/25 05:17:12 DEBUG : file2: reading active writers 2024/04/25 05:17:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:13 DEBUG : : Looking for writers 2024/04/25 05:17:13 DEBUG : file1: reading active writers 2024/04/25 05:17:13 DEBUG : file2: reading active writers 2024/04/25 05:17:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:14 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc000a64870 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000727208 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641221184} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943874095122608901 ext:381666397516 loc:0x3a135e0} ATime:{wall:13943874095122877735 ext:381666666350 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, "file2": &{c:0xc000a64870 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000727308 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641221440} name:file2 opens:0 downloaders: o:0xc0007ef180 fd: info:{ModTime:{wall:13943874095123823959 ext:381667612574 loc:0x3a135e0} ATime:{wall:13943874095124001082 ext:381667789697 loc:0x3a135e0} Size:0 Rs:[] Fingerprint:0,2024-04-25 05:15:30.413171031 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709 Dirty:false} writeBackID:2 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:17:14 DEBUG : >WaitForWriters: 2024/04/25 05:17:14 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteNoWrite (106.31s) === RUN TestRWFileHandleSizeTruncateExisting run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:17:16 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:17:16 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:17:16 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:16 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:16 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:16 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17: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:17:18.052822 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:17:18.052856 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-xayodap5kapovik2neruhan1'": 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:17:18 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:17:18 DEBUG : : Looking for writers 2024/04/25 05:17:18 DEBUG : >WaitForWriters: 2024/04/25 05:17:18 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeTruncateExisting (3.81s) === RUN TestRWFileHandleSizeCreateExisting run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:17:20 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:17:20 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:17:20 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:20 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:20 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:20 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17: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:17:21.835011 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:17:21.835046 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-xayodap5kapovik2neruhan1'": 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:17:21 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:17:21 DEBUG : : Looking for writers 2024/04/25 05:17:21 DEBUG : >WaitForWriters: 2024/04/25 05:17:21 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeCreateExisting (3.94s) === RUN TestRWFileModTimeWithOpenWriters run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:17:24 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:17:24 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:17:24 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:24 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:24 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:24 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:24 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:17:24 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:17:24 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:17:24 DEBUG : file1: newRWFileHandle: 2024/04/25 05:17:24 DEBUG : file1(0xc000aa6400): openPending: 2024/04/25 05:17:24 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:17:24 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:17:24 DEBUG : file1(0xc000aa6400): >openPending: err= 2024/04/25 05:17:24 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:17:24 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:17:24 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:17:24 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:17:25.157514 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:17:25.157540 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-xayodap5kapovik2neruhan1'": 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:17:25 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:17:25 DEBUG : : Looking for writers 2024/04/25 05:17:25 DEBUG : file1: reading active writers 2024/04/25 05:17:25 DEBUG : file1: active writers 1 2024/04/25 05:17:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:17:25 DEBUG : : Looking for writers 2024/04/25 05:17:25 DEBUG : file1: reading active writers 2024/04/25 05:17:25 DEBUG : file1: active writers 1 2024/04/25 05:17:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:17:25 DEBUG : : Looking for writers 2024/04/25 05:17:25 DEBUG : file1: reading active writers 2024/04/25 05:17:25 DEBUG : file1: active writers 1 2024/04/25 05:17:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:17:25 DEBUG : : Looking for writers 2024/04/25 05:17:25 DEBUG : file1: reading active writers 2024/04/25 05:17:25 DEBUG : file1: active writers 1 2024/04/25 05:17:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:17:25 DEBUG : : Looking for writers 2024/04/25 05:17:25 DEBUG : file1: reading active writers 2024/04/25 05:17:25 DEBUG : file1: active writers 1 2024/04/25 05:17:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:17:25 DEBUG : : Looking for writers 2024/04/25 05:17:25 DEBUG : file1: reading active writers 2024/04/25 05:17:25 DEBUG : file1: active writers 1 2024/04/25 05:17:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:17:25 DEBUG : : Looking for writers 2024/04/25 05:17:25 DEBUG : file1: reading active writers 2024/04/25 05:17:25 DEBUG : file1: active writers 1 2024/04/25 05:17:25 DEBUG : Still 1 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:17:26 DEBUG : : Looking for writers 2024/04/25 05:17:26 DEBUG : file1: reading active writers 2024/04/25 05:17:26 DEBUG : file1: active writers 1 2024/04/25 05:17:26 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:27 DEBUG : : Looking for writers 2024/04/25 05:17:27 DEBUG : file1: reading active writers 2024/04/25 05:17:27 DEBUG : file1: active writers 1 2024/04/25 05:17:27 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:28 DEBUG : : Looking for writers 2024/04/25 05:17:28 DEBUG : file1: reading active writers 2024/04/25 05:17:28 DEBUG : file1: active writers 1 2024/04/25 05:17:28 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:29 DEBUG : : Looking for writers 2024/04/25 05:17:29 DEBUG : file1: reading active writers 2024/04/25 05:17:29 DEBUG : file1: active writers 1 2024/04/25 05:17:29 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:30 DEBUG : : Looking for writers 2024/04/25 05:17:30 DEBUG : file1: reading active writers 2024/04/25 05:17:30 DEBUG : file1: active writers 1 2024/04/25 05:17:30 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:31 DEBUG : : Looking for writers 2024/04/25 05:17:31 DEBUG : file1: reading active writers 2024/04/25 05:17:31 DEBUG : file1: active writers 1 2024/04/25 05:17:31 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:32 DEBUG : : Looking for writers 2024/04/25 05:17:32 DEBUG : file1: reading active writers 2024/04/25 05:17:32 DEBUG : file1: active writers 1 2024/04/25 05:17:32 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:33 DEBUG : : Looking for writers 2024/04/25 05:17:33 DEBUG : file1: reading active writers 2024/04/25 05:17:33 DEBUG : file1: active writers 1 2024/04/25 05:17:33 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:34 DEBUG : : Looking for writers 2024/04/25 05:17:34 DEBUG : file1: reading active writers 2024/04/25 05:17:34 DEBUG : file1: active writers 1 2024/04/25 05:17:34 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:35 DEBUG : : Looking for writers 2024/04/25 05:17:35 DEBUG : file1: reading active writers 2024/04/25 05:17:35 DEBUG : file1: active writers 1 2024/04/25 05:17:35 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:36 DEBUG : : Looking for writers 2024/04/25 05:17:36 DEBUG : file1: reading active writers 2024/04/25 05:17:36 DEBUG : file1: active writers 1 2024/04/25 05:17:36 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:37 DEBUG : : Looking for writers 2024/04/25 05:17:37 DEBUG : file1: reading active writers 2024/04/25 05:17:37 DEBUG : file1: active writers 1 2024/04/25 05:17:37 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:38 DEBUG : : Looking for writers 2024/04/25 05:17:38 DEBUG : file1: reading active writers 2024/04/25 05:17:38 DEBUG : file1: active writers 1 2024/04/25 05:17:38 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:39 DEBUG : : Looking for writers 2024/04/25 05:17:39 DEBUG : file1: reading active writers 2024/04/25 05:17:39 DEBUG : file1: active writers 1 2024/04/25 05:17:39 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:40 DEBUG : : Looking for writers 2024/04/25 05:17:40 DEBUG : file1: reading active writers 2024/04/25 05:17:40 DEBUG : file1: active writers 1 2024/04/25 05:17:40 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:41 DEBUG : : Looking for writers 2024/04/25 05:17:41 DEBUG : file1: reading active writers 2024/04/25 05:17:41 DEBUG : file1: active writers 1 2024/04/25 05:17:41 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:42 DEBUG : : Looking for writers 2024/04/25 05:17:42 DEBUG : file1: reading active writers 2024/04/25 05:17:42 DEBUG : file1: active writers 1 2024/04/25 05:17:42 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:43 DEBUG : : Looking for writers 2024/04/25 05:17:43 DEBUG : file1: reading active writers 2024/04/25 05:17:43 DEBUG : file1: active writers 1 2024/04/25 05:17:43 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:44 DEBUG : : Looking for writers 2024/04/25 05:17:44 DEBUG : file1: reading active writers 2024/04/25 05:17:44 DEBUG : file1: active writers 1 2024/04/25 05:17:44 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:45 DEBUG : : Looking for writers 2024/04/25 05:17:45 DEBUG : file1: reading active writers 2024/04/25 05:17:45 DEBUG : file1: active writers 1 2024/04/25 05:17:45 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:46 DEBUG : : Looking for writers 2024/04/25 05:17:46 DEBUG : file1: reading active writers 2024/04/25 05:17:46 DEBUG : file1: active writers 1 2024/04/25 05:17:46 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:47 DEBUG : : Looking for writers 2024/04/25 05:17:47 DEBUG : file1: reading active writers 2024/04/25 05:17:47 DEBUG : file1: active writers 1 2024/04/25 05:17:47 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:48 DEBUG : : Looking for writers 2024/04/25 05:17:48 DEBUG : file1: reading active writers 2024/04/25 05:17:48 DEBUG : file1: active writers 1 2024/04/25 05:17:48 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:49 DEBUG : : Looking for writers 2024/04/25 05:17:49 DEBUG : file1: reading active writers 2024/04/25 05:17:49 DEBUG : file1: active writers 1 2024/04/25 05:17:49 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:50 DEBUG : : Looking for writers 2024/04/25 05:17:50 DEBUG : file1: reading active writers 2024/04/25 05:17:50 DEBUG : file1: active writers 1 2024/04/25 05:17:50 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:51 DEBUG : : Looking for writers 2024/04/25 05:17:51 DEBUG : file1: reading active writers 2024/04/25 05:17:51 DEBUG : file1: active writers 1 2024/04/25 05:17:51 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:52 DEBUG : : Looking for writers 2024/04/25 05:17:52 DEBUG : file1: reading active writers 2024/04/25 05:17:52 DEBUG : file1: active writers 1 2024/04/25 05:17:52 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:53 DEBUG : : Looking for writers 2024/04/25 05:17:53 DEBUG : file1: reading active writers 2024/04/25 05:17:53 DEBUG : file1: active writers 1 2024/04/25 05:17:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:54 DEBUG : : Looking for writers 2024/04/25 05:17:54 DEBUG : file1: reading active writers 2024/04/25 05:17:54 DEBUG : file1: active writers 1 2024/04/25 05:17:54 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:17:55 ERROR : Exiting even though 1 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc00063ac30 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc00079af08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824641695552} name:file1 opens:1 downloaders: o: fd:0xc0007043d0 info:{ModTime:{wall:13943874217608962026 ext:495746182695 loc:0x3a135e0} ATime:{wall:13943874217608962026 ext:495746182695 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:0 pendingAccesses:0 modified:true beingReset:false}, } 2024/04/25 05:17:55 DEBUG : >WaitForWriters: 2024/04/25 05:17:55 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileModTimeWithOpenWriters (32.06s) === RUN TestRWCacheUpdate run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:17:56 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:17:56 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:17:56 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:56 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:56 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:56 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-xayodap5kapovik2neruhan1" 2024/04/25 05:17:56 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:17:56 DEBUG : : forgetting directory cache 2024/04/25 05:17:56.798984 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:17:56.799018 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-xayodap5kapovik2neruhan1'": 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:17:56 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:17:56 DEBUG : : Looking for writers 2024/04/25 05:17:56 DEBUG : >WaitForWriters: 2024/04/25 05:17:56 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWCacheUpdate (1.62s) === RUN TestCaseSensitivity run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:17:58.435975 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:17:58.436002 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-xayodap5kapovik2neruhan1'": 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.70s) === RUN TestUnicodeNormalization run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:18:00.174229 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:18:00.174262 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-xayodap5kapovik2neruhan1'": 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.68s) === RUN TestVFSStat run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:18:01 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:18:01 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:18:02.105200 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:18:02.105247 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-xayodap5kapovik2neruhan1'": 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:18:03 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:18:03 DEBUG : : Looking for writers 2024/04/25 05:18:03 DEBUG : >WaitForWriters: --- FAIL: TestVFSStat (3.45s) === RUN TestVFSStatParent run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:18:04 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:18:04 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:18:05.502888 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:18:05.502917 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-xayodap5kapovik2neruhan1'": 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:18:07 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:18:07 DEBUG : : Looking for writers 2024/04/25 05:18:07 DEBUG : >WaitForWriters: --- FAIL: TestVFSStatParent (3.50s) === RUN TestVFSOpenFile run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:18:08 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:18:08 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:18:08.987882 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:18:08.987911 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-xayodap5kapovik2neruhan1'": 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:18:10 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:18:10 DEBUG : : Looking for writers 2024/04/25 05:18:10 DEBUG : >WaitForWriters: --- FAIL: TestVFSOpenFile (3.53s) === RUN TestVFSRename run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:18:11 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:18:11 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:18:13.306058 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:18:13.306097 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-xayodap5kapovik2neruhan1'": 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:18:13 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:18:13 DEBUG : : Looking for writers 2024/04/25 05:18:13 DEBUG : >WaitForWriters: --- FAIL: TestVFSRename (3.92s) === RUN TestWriteFileHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:18:15 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:18:15 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:18:15 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:18:15 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:18:15 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:18:15 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:18:15 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:18:15 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:18:15 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2024/04/25 05:18:15 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2024/04/25 05:18:15 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2024/04/25 05:18:15 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': File to upload is small (5 bytes), uploading instead of streaming 2024/04/25 05:18:16.895475 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:18:16.895507 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:18:18 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:18:18 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:18:18 DEBUG : file1: Remove: 2024/04/25 05:18:18 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:18:18 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:18:59 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:18:59 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:18:59 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:18:59 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:18:59 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:18:59 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:18:59 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:19:10 DEBUG : : forgetting directory cache 2024/04/25 05:19:15 DEBUG : : forgetting directory cache 2024/04/25 05:19:16.718163 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:19:16.718195 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:19:18 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:19:18 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:19:18 DEBUG : file1: Remove: 2024/04/25 05:19:18 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:19:18 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:19:18 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:19:18 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:19:18 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:19:18 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:19:18 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:19:18 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:19:18 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:19:18 DEBUG : : Looking for writers 2024/04/25 05:19:18 DEBUG : file1: reading active writers 2024/04/25 05:19:18 DEBUG : file1: active writers 1 2024/04/25 05:19:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms 2024/04/25 05:19:18 DEBUG : : Looking for writers 2024/04/25 05:19:18 DEBUG : file1: reading active writers 2024/04/25 05:19:18 DEBUG : file1: active writers 1 2024/04/25 05:19:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms 2024/04/25 05:19:18 DEBUG : : Looking for writers 2024/04/25 05:19:18 DEBUG : file1: reading active writers 2024/04/25 05:19:18 DEBUG : file1: active writers 1 2024/04/25 05:19:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms 2024/04/25 05:19:18 DEBUG : : Looking for writers 2024/04/25 05:19:18 DEBUG : file1: reading active writers 2024/04/25 05:19:18 DEBUG : file1: active writers 1 2024/04/25 05:19:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms 2024/04/25 05:19:18 DEBUG : : Looking for writers 2024/04/25 05:19:18 DEBUG : file1: reading active writers 2024/04/25 05:19:18 DEBUG : file1: active writers 1 2024/04/25 05:19:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms 2024/04/25 05:19:18 DEBUG : : Looking for writers 2024/04/25 05:19:18 DEBUG : file1: reading active writers 2024/04/25 05:19:18 DEBUG : file1: active writers 1 2024/04/25 05:19:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms 2024/04/25 05:19:18 DEBUG : : Looking for writers 2024/04/25 05:19:18 DEBUG : file1: reading active writers 2024/04/25 05:19:18 DEBUG : file1: active writers 1 2024/04/25 05:19:18 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms 2024/04/25 05:19:19 DEBUG : : forgetting directory cache 2024/04/25 05:19:19 DEBUG : : Looking for writers 2024/04/25 05:19:19 DEBUG : file1: reading active writers 2024/04/25 05:19:19 DEBUG : file1: active writers 1 2024/04/25 05:19:19 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:20 DEBUG : : Looking for writers 2024/04/25 05:19:20 DEBUG : file1: reading active writers 2024/04/25 05:19:20 DEBUG : file1: active writers 1 2024/04/25 05:19:20 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:21 DEBUG : : Looking for writers 2024/04/25 05:19:21 DEBUG : file1: reading active writers 2024/04/25 05:19:21 DEBUG : file1: active writers 1 2024/04/25 05:19:21 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:22 DEBUG : : Looking for writers 2024/04/25 05:19:22 DEBUG : file1: reading active writers 2024/04/25 05:19:22 DEBUG : file1: active writers 1 2024/04/25 05:19:22 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:22 DEBUG : : forgetting directory cache 2024/04/25 05:19:23 DEBUG : : Looking for writers 2024/04/25 05:19:23 DEBUG : file1: reading active writers 2024/04/25 05:19:23 DEBUG : file1: active writers 1 2024/04/25 05:19:23 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:24 DEBUG : : Looking for writers 2024/04/25 05:19:24 DEBUG : file1: reading active writers 2024/04/25 05:19:24 DEBUG : file1: active writers 1 2024/04/25 05:19:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:25 DEBUG : : Looking for writers 2024/04/25 05:19:25 DEBUG : file1: reading active writers 2024/04/25 05:19:25 DEBUG : file1: active writers 1 2024/04/25 05:19:25 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:26 DEBUG : : Looking for writers 2024/04/25 05:19:26 DEBUG : file1: reading active writers 2024/04/25 05:19:26 DEBUG : file1: active writers 1 2024/04/25 05:19:26 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:26 DEBUG : : forgetting directory cache 2024/04/25 05:19:27 DEBUG : : Looking for writers 2024/04/25 05:19:27 DEBUG : file1: reading active writers 2024/04/25 05:19:27 DEBUG : file1: active writers 1 2024/04/25 05:19:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:28 DEBUG : : Looking for writers 2024/04/25 05:19:28 DEBUG : file1: reading active writers 2024/04/25 05:19:28 DEBUG : file1: active writers 1 2024/04/25 05:19:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:29 DEBUG : : Looking for writers 2024/04/25 05:19:29 DEBUG : file1: reading active writers 2024/04/25 05:19:29 DEBUG : file1: active writers 1 2024/04/25 05:19:29 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:30 DEBUG : : forgetting directory cache 2024/04/25 05:19:30 DEBUG : : Looking for writers 2024/04/25 05:19:30 DEBUG : file1: reading active writers 2024/04/25 05:19:30 DEBUG : file1: active writers 1 2024/04/25 05:19:30 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:31 DEBUG : : Looking for writers 2024/04/25 05:19:31 DEBUG : file1: reading active writers 2024/04/25 05:19:31 DEBUG : file1: active writers 1 2024/04/25 05:19:31 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:32 DEBUG : : Looking for writers 2024/04/25 05:19:32 DEBUG : file1: reading active writers 2024/04/25 05:19:32 DEBUG : file1: active writers 1 2024/04/25 05:19:32 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:33 DEBUG : : Looking for writers 2024/04/25 05:19:33 DEBUG : file1: reading active writers 2024/04/25 05:19:33 DEBUG : file1: active writers 1 2024/04/25 05:19:33 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:34 DEBUG : : forgetting directory cache 2024/04/25 05:19:34 DEBUG : : Looking for writers 2024/04/25 05:19:34 DEBUG : file1: reading active writers 2024/04/25 05:19:34 DEBUG : file1: active writers 1 2024/04/25 05:19:34 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:35 DEBUG : : Looking for writers 2024/04/25 05:19:35 DEBUG : file1: reading active writers 2024/04/25 05:19:35 DEBUG : file1: active writers 1 2024/04/25 05:19:35 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:36 DEBUG : : Looking for writers 2024/04/25 05:19:36 DEBUG : file1: reading active writers 2024/04/25 05:19:36 DEBUG : file1: active writers 1 2024/04/25 05:19:36 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:37 DEBUG : : Looking for writers 2024/04/25 05:19:37 DEBUG : file1: reading active writers 2024/04/25 05:19:37 DEBUG : file1: active writers 1 2024/04/25 05:19:37 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:37 DEBUG : : forgetting directory cache 2024/04/25 05:19:38 DEBUG : : Looking for writers 2024/04/25 05:19:38 DEBUG : file1: reading active writers 2024/04/25 05:19:38 DEBUG : file1: active writers 1 2024/04/25 05:19:38 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:39 DEBUG : : Looking for writers 2024/04/25 05:19:39 DEBUG : file1: reading active writers 2024/04/25 05:19:39 DEBUG : file1: active writers 1 2024/04/25 05:19:39 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:40 DEBUG : : Looking for writers 2024/04/25 05:19:40 DEBUG : file1: reading active writers 2024/04/25 05:19:40 DEBUG : file1: active writers 1 2024/04/25 05:19:40 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:41 DEBUG : : Looking for writers 2024/04/25 05:19:41 DEBUG : file1: reading active writers 2024/04/25 05:19:41 DEBUG : file1: active writers 1 2024/04/25 05:19:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:41 DEBUG : : forgetting directory cache 2024/04/25 05:19:42 DEBUG : : Looking for writers 2024/04/25 05:19:42 DEBUG : file1: reading active writers 2024/04/25 05:19:42 DEBUG : file1: active writers 1 2024/04/25 05:19:42 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:43 DEBUG : : Looking for writers 2024/04/25 05:19:43 DEBUG : file1: reading active writers 2024/04/25 05:19:43 DEBUG : file1: active writers 1 2024/04/25 05:19:43 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:44 DEBUG : : Looking for writers 2024/04/25 05:19:44 DEBUG : file1: reading active writers 2024/04/25 05:19:44 DEBUG : file1: active writers 1 2024/04/25 05:19:44 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:45 DEBUG : : forgetting directory cache 2024/04/25 05:19:45 DEBUG : : Looking for writers 2024/04/25 05:19:45 DEBUG : file1: reading active writers 2024/04/25 05:19:45 DEBUG : file1: active writers 1 2024/04/25 05:19:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:46 DEBUG : : Looking for writers 2024/04/25 05:19:46 DEBUG : file1: reading active writers 2024/04/25 05:19:46 DEBUG : file1: active writers 1 2024/04/25 05:19:46 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:47 DEBUG : : Looking for writers 2024/04/25 05:19:47 DEBUG : file1: reading active writers 2024/04/25 05:19:47 DEBUG : file1: active writers 1 2024/04/25 05:19:47 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:19:48 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s Cache: 2024/04/25 05:19:48 DEBUG : >WaitForWriters: 2024/04/25 05:19:49 DEBUG : : forgetting directory cache --- FAIL: TestWriteFileHandleMethods (93.70s) === RUN TestWriteFileHandleWriteAt run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:19:49 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:19:49 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:19:49 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:19:49 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:19:49 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:19:49 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:19:49 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:19:49 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:19:49 DEBUG : file1: waiting for in-sequence write to 100 for 1s 2024/04/25 05:19:50 DEBUG : file1: aborting in-sequence write wait, off=100 2024/04/25 05:19:50 DEBUG : file1: failed to wait for in-sequence write to 100 2024/04/25 05:19:50 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes 2024/04/25 05:19:50 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': File to upload is small (11 bytes), uploading instead of streaming 2024/04/25 05:19:51.613812 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:19:51.613857 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:19:52 DEBUG : : forgetting directory cache 2024/04/25 05:19:53 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:19:53 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:19:53 DEBUG : file1: Remove: 2024/04/25 05:19:53 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:19:53 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:19:53 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 2024/04/25 05:19:56 DEBUG : : forgetting directory cache 2024/04/25 05:20:00 DEBUG : : forgetting directory cache 2024/04/25 05:20:04 DEBUG : : forgetting directory cache 2024/04/25 05:20:08 DEBUG : : forgetting directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 2024/04/25 05:20:11 DEBUG : : forgetting directory cache fstest.go:295: Flushing the directory cache 2024/04/25 05:20:15 DEBUG : : forgetting directory cache 2024/04/25 05:20:19 DEBUG : : forgetting directory cache 2024/04/25 05:20:23 DEBUG : : forgetting directory cache 2024/04/25 05:20:26 DEBUG : : forgetting directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 2024/04/25 05:20:30 DEBUG : : forgetting directory cache 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:20:34 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:20:34 DEBUG : : Looking for writers 2024/04/25 05:20:34 DEBUG : >WaitForWriters: 2024/04/25 05:20:34 DEBUG : : forgetting directory cache 2024/04/25 05:20:38 DEBUG : : forgetting directory cache 2024/04/25 05:20:41 DEBUG : : forgetting directory cache 2024/04/25 05:20:45 DEBUG : : forgetting directory cache 2024/04/25 05:20:49 DEBUG : : forgetting directory cache --- FAIL: TestWriteFileHandleWriteAt (62.12s) === RUN TestWriteFileHandleFlush run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:20:51 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:20:51 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:20:51 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:20:51 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:20:51 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:20:51 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:20:51 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:20:51 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2024/04/25 05:20:51 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:20:51 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': File to upload is small (5 bytes), uploading instead of streaming 2024/04/25 05:20:52.806507 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:20:52.806537 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:20:53 DEBUG : : forgetting directory cache 2024/04/25 05:20:54 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:20:54 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:20:54 DEBUG : file1: Remove: 2024/04/25 05:20:54 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:20:54 DEBUG : file1: >Remove: err= 2024/04/25 05:20:54 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:20:54 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:20:54 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:20:54 DEBUG : : Looking for writers 2024/04/25 05:20:54 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleFlush (3.93s) === RUN TestWriteFileHandleRelease run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:20:55 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:20:55 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:20:55 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:20:55 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:20:55 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:20:55 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:20:55 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:20:55 DEBUG : file1: WriteFileHandle.Release closing 2024/04/25 05:20:55 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:20:55 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:20:56.702873 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:20:56.702915 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:20:57 DEBUG : : forgetting directory cache 2024/04/25 05:20:58 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:20:58 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:20:58 DEBUG : file1: Remove: 2024/04/25 05:20:58 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:20:58 DEBUG : file1: >Remove: err= 2024/04/25 05:20:58 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:20:58 DEBUG : file1: WriteFileHandle.Release nothing to do 2024/04/25 05:20:58 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:20:58 DEBUG : : Looking for writers 2024/04/25 05:20:58 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleRelease (3.90s) === RUN TestWriteFileModTimeWithOpenWriters run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:20:59 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:20:59 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:20:59 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:20:59 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:20:59 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:20:59 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:20:59 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:20:59 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:20:59 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': File to upload is small (2 bytes), uploading instead of streaming 2024/04/25 05:21:00.598808 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:21:00.598849 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:21:01 DEBUG : : forgetting directory cache 2024/04/25 05:21: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:21:02 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:21:02 DEBUG : file1: Remove: 2024/04/25 05:21:02 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:21:02 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:21:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:02 DEBUG : : Looking for writers 2024/04/25 05:21:02 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileModTimeWithOpenWriters (3.94s) === RUN TestFileReadAtZeroLength run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:21:03 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:21:03 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:03 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:21:03 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:21:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:21:03 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:21:03 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:21:03 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:21:03 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:21:04.529322 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:21:04.529370 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:21:04 DEBUG : : forgetting directory cache 2024/04/25 05:21:06 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:21:06 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:21:06 DEBUG : file1: Remove: 2024/04/25 05:21:06 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:21:06 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:21:06 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2024/04/25 05:21:06 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:21:06 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:06 DEBUG : : Looking for writers 2024/04/25 05:21:06 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtZeroLength (3.95s) === RUN TestFileReadAtNonZeroLength run.go:180: Remote "proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1'", Local "Local file system at /tmp/rclone27614605", Modify Window "1s" 2024/04/25 05:21:07 INFO : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': poll-interval is not supported by this remote 2024/04/25 05:21:07 NOTICE: proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:21:07 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:21:07 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:21:07 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:21:07 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:21:07 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:21:07 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:21:07 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': File to upload is small (100 bytes), uploading instead of streaming 2024/04/25 05:21:08.429791 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:21:08.429829 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:21:08 DEBUG : : forgetting directory cache 2024/04/25 05:21: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:21:10 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:21:10 DEBUG : file1: Remove: 2024/04/25 05:21:10 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:21:10 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:21:10 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2024/04/25 05:21:10 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:21:10 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:21:10 DEBUG : : Looking for writers 2024/04/25 05:21:10 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtNonZeroLength (3.96s) FAIL 2024/04/25 05:21:10 DEBUG : proton drive root link ID 'rclone-test-xayodap5kapovik2neruhan1': 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 12m2.89490101s (try 3/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]