"./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 5/5) 2024/04/25 05:31:37 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:31:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/25 05:31:37 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': Has cached credentials 2024/04/25 05:31:38 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': Used cached credential to initialize the ProtonDrive API 2024/04/25 05:31:38 DEBUG : Creating backend with remote "/tmp/rclone4022115362" === RUN TestDirHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:31:38 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:31:38 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:40.760452 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:31:40.760490 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:31:40 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:40 DEBUG : : Looking for writers 2024/04/25 05:31:40 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleMethods (3.46s) === RUN TestDirHandleReaddir run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:31:42 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:31:42 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:43.449007 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:31:43.449032 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:31:43 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:43 DEBUG : : Looking for writers 2024/04/25 05:31:43 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddir (2.67s) === RUN TestDirHandleReaddirnames run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:31:44 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:31:44 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:46.218705 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:31:46.218732 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:31:46 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:46 DEBUG : : Looking for writers 2024/04/25 05:31:46 DEBUG : >WaitForWriters: --- FAIL: TestDirHandleReaddirnames (2.78s) === RUN TestDirMethods run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:31:47 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:31:47 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:48.971720 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:31:48.971751 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:31:48 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:48 DEBUG : : Looking for writers 2024/04/25 05:31:48 DEBUG : >WaitForWriters: --- FAIL: TestDirMethods (2.63s) === RUN TestDirForgetAll run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:31:50 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:31:50 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:51.557721 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:31:51.557763 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:31:51 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:51 DEBUG : : Looking for writers 2024/04/25 05:31:51 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetAll (2.79s) === RUN TestDirForgetPath run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:31:52 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:31:52 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:54.351968 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:31:54.351993 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:31:54 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:54 DEBUG : : Looking for writers 2024/04/25 05:31:54 DEBUG : >WaitForWriters: --- FAIL: TestDirForgetPath (2.65s) === RUN TestDirWalk run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:31:55 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:31:55 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:31:57.166797 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:31:57.166826 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:31:57 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:31:57 DEBUG : : Looking for writers 2024/04/25 05:31:57 DEBUG : >WaitForWriters: --- FAIL: TestDirWalk (2.93s) === RUN TestDirSetModTime run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:31:58 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:31:58 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:00.000596 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:32:00.000637 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:00 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:00 DEBUG : : Looking for writers 2024/04/25 05:32:00 DEBUG : >WaitForWriters: --- FAIL: TestDirSetModTime (2.82s) === RUN TestDirStat run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:01 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:01 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:02.761325 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:32:02.761375 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:02 DEBUG : : Looking for writers 2024/04/25 05:32:02 DEBUG : >WaitForWriters: --- FAIL: TestDirStat (2.78s) === RUN TestDirReadDirAll run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:04 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:04 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:05.469760 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:32:05.469807 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:05 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:05 DEBUG : : Looking for writers 2024/04/25 05:32:05 DEBUG : >WaitForWriters: --- FAIL: TestDirReadDirAll (2.83s) === RUN TestDirOpen run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:06 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:06 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:08.559849 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:32:08.559905 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:08 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:08 DEBUG : : Looking for writers 2024/04/25 05:32:08 DEBUG : >WaitForWriters: --- FAIL: TestDirOpen (2.95s) === RUN TestDirCreate run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:09 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:09 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:11.214855 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:32:11.214888 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:11 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:11 DEBUG : : Looking for writers 2024/04/25 05:32:11 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (2.76s) === RUN TestDirMkdir run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:12 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:12 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:14.150724 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:32:14.150759 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:14 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:14 DEBUG : : Looking for writers 2024/04/25 05:32:14 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdir (2.80s) === RUN TestDirMkdirSub run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:15 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:15 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:16.921556 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:32:16.921595 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:16 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:16 DEBUG : : Looking for writers 2024/04/25 05:32:16 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdirSub (2.92s) === RUN TestDirRemove run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:18 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:18 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:19.828445 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:32:19.828481 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:19 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:19 DEBUG : : Looking for writers 2024/04/25 05:32:19 DEBUG : >WaitForWriters: --- FAIL: TestDirRemove (2.88s) === RUN TestDirRemoveAll run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:21 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:21 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:22.699796 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:32:22.699831 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:22 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:22 DEBUG : : Looking for writers 2024/04/25 05:32:22 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveAll (2.80s) === RUN TestDirRemoveName run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:24 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:24 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:25.483584 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:32:25.483613 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:25 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:25 DEBUG : : Looking for writers 2024/04/25 05:32:25 DEBUG : >WaitForWriters: --- FAIL: TestDirRemoveName (2.74s) === RUN TestDirRename run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:26 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:26 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:28.146350 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:32:28.146379 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:28 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:28 DEBUG : : Looking for writers 2024/04/25 05:32:28 DEBUG : >WaitForWriters: --- FAIL: TestDirRename (2.82s) === RUN TestFileMethods run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:29 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:29 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:31.001183 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:32:31.001223 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:31 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:31 DEBUG : : Looking for writers 2024/04/25 05:32:31 DEBUG : >WaitForWriters: --- FAIL: TestFileMethods (2.79s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:32 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:32 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:33.937940 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:32:33.937980 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:33 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:33 DEBUG : : Looking for writers 2024/04/25 05:32:33 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:35 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:35 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:36.763912 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:32:36.763941 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:36 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:36 DEBUG : : Looking for writers 2024/04/25 05:32:36 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:38 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:38 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:39.481525 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:32:39.481555 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:39 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:39 DEBUG : : Looking for writers 2024/04/25 05:32:39 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:40 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:40 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:32:40 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:40 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:40 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:40 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:40 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:32:42.231684 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:32:42.231723 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:42 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:42 DEBUG : : Looking for writers 2024/04/25 05:32:42 DEBUG : >WaitForWriters: 2024/04/25 05:32:42 DEBUG : vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:43 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:43 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:32:43 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:43 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:43 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:43 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:43 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:32:45.071529 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:32:45.071564 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:45 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:45 DEBUG : : Looking for writers 2024/04/25 05:32:45 DEBUG : >WaitForWriters: 2024/04/25 05:32:45 DEBUG : vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:46 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:46 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:32:46 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:46 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:46 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32:46 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:32: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:32:47.853532 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:32:47.853561 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:47 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:47 DEBUG : : Looking for writers 2024/04/25 05:32:47 DEBUG : >WaitForWriters: 2024/04/25 05:32:47 DEBUG : vfs cache: cleaner exiting --- FAIL: TestFileSetModTime (16.83s) --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (2.95s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=false (2.68s) --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (2.86s) --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (2.76s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (2.82s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (2.75s) === RUN TestFileOpenRead run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:49 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:49 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:50.693296 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:32:50.693327 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:50 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:50 DEBUG : : Looking for writers 2024/04/25 05:32:50 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenRead (2.85s) === RUN TestFileOpenWrite run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:52 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:52 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:53.547851 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:32:53.547894 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:53 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:53 DEBUG : : Looking for writers 2024/04/25 05:32:53 DEBUG : >WaitForWriters: --- FAIL: TestFileOpenWrite (2.94s) === RUN TestFileRemove run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:55 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:55 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:56.531331 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:32:56.531374 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:56 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:56 DEBUG : : Looking for writers 2024/04/25 05:32:56 DEBUG : >WaitForWriters: --- FAIL: TestFileRemove (2.90s) === RUN TestFileRemoveAll run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:32:57 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:32:57 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:32:59.514980 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:32:59.515006 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:32:59 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:32:59 DEBUG : : Looking for writers 2024/04/25 05:32:59 DEBUG : >WaitForWriters: --- FAIL: TestFileRemoveAll (3.04s) === RUN TestFileOpen run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:01 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:01 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:02.415212 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:33:02.415249 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:02 DEBUG : : Looking for writers 2024/04/25 05:33:02 DEBUG : >WaitForWriters: --- FAIL: TestFileOpen (2.93s) === RUN TestFileRename === RUN TestFileRename/off,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:03 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:03 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:05.217634 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:33:05.217667 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:05 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:05 DEBUG : : Looking for writers 2024/04/25 05:33:05 DEBUG : >WaitForWriters: === RUN TestFileRename/minimal,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:06 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:06 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:06 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:06 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:06 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:06 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:06 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:06 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:33:08.060809 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:33:08.060848 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:08 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:08 DEBUG : : Looking for writers 2024/04/25 05:33:08 DEBUG : >WaitForWriters: 2024/04/25 05:33:08 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/minimal,forceCache=true run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:09 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:09 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:09 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:09 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:09 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:09 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:09 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:09 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:33:10.815462 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:33:10.815505 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:10 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:10 DEBUG : : Looking for writers 2024/04/25 05:33:10 DEBUG : >WaitForWriters: 2024/04/25 05:33:10 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:12 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:12 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:12 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:12 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:12 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:12 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33: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:33:13.734392 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:33:13.734420 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:13 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:13 DEBUG : : Looking for writers 2024/04/25 05:33:13 DEBUG : >WaitForWriters: 2024/04/25 05:33:13 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/writes,forceCache=true run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:15 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:15 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:15 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:15 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:15 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:15 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:15 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:33:16.446029 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:33:16.446069 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:16 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:16 DEBUG : : Looking for writers 2024/04/25 05:33:16 DEBUG : >WaitForWriters: 2024/04/25 05:33:16 DEBUG : vfs cache: cleaner exiting === RUN TestFileRename/full,forceCache=false run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:17 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:17 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:17 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:17 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:17 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:17 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:17 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:33:19.125233 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:33:19.125276 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:19 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:19 DEBUG : : Looking for writers 2024/04/25 05:33:19 DEBUG : >WaitForWriters: 2024/04/25 05:33:19 DEBUG : vfs cache: cleaner exiting --- FAIL: TestFileRename (16.51s) --- FAIL: TestFileRename/off,forceCache=false (2.77s) --- FAIL: TestFileRename/minimal,forceCache=false (2.74s) --- FAIL: TestFileRename/minimal,forceCache=true (2.91s) --- FAIL: TestFileRename/writes,forceCache=false (2.71s) --- FAIL: TestFileRename/writes,forceCache=true (2.82s) --- FAIL: TestFileRename/full,forceCache=false (2.56s) === RUN TestReadFileHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:20 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:20 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:21.804786 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:33:21.804824 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:21 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:21 DEBUG : : Looking for writers 2024/04/25 05:33:21 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleMethods (2.89s) === RUN TestReadFileHandleSeek run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:23 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:23 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:24.713900 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:33:24.713928 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:24 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:24 DEBUG : : Looking for writers 2024/04/25 05:33:24 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleSeek (2.92s) === RUN TestReadFileHandleReadAt run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:26 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:26 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:27.578399 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:33:27.578428 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:27 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:27 DEBUG : : Looking for writers 2024/04/25 05:33:27 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleReadAt (2.84s) === RUN TestReadFileHandleFlush run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:29 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:29 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:30.412043 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:33:30.412074 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:30 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:30 DEBUG : : Looking for writers 2024/04/25 05:33:30 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleFlush (2.74s) === RUN TestReadFileHandleRelease run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:31 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:31 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:33:33.223745 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:33:33.223786 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:33 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:33 DEBUG : : Looking for writers 2024/04/25 05:33:33 DEBUG : >WaitForWriters: --- FAIL: TestReadFileHandleRelease (2.74s) === RUN TestRWFileHandleMethodsRead run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:34 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:34 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:34 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:34 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:34 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:34 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33: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:33:35.891393 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:33:35.891423 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:35 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:35 DEBUG : : Looking for writers 2024/04/25 05:33:35 DEBUG : >WaitForWriters: 2024/04/25 05:33:35 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsRead (2.83s) === RUN TestRWFileHandleSeek run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:37 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:37 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:37 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:37 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:37 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:37 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:37 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:33:38.875415 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:33:38.875446 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:38 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:38 DEBUG : : Looking for writers 2024/04/25 05:33:38 DEBUG : >WaitForWriters: 2024/04/25 05:33:38 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSeek (2.82s) === RUN TestRWFileHandleReadAt run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:40 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:40 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:40 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:40 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:40 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:40 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:40 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:33:41.620605 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:33:41.620650 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:41 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:41 DEBUG : : Looking for writers 2024/04/25 05:33:41 DEBUG : >WaitForWriters: 2024/04/25 05:33:41 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReadAt (2.73s) === RUN TestRWFileHandleFlushRead run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:42 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:42 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:42 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:42 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:42 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:42 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:42 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:33:44.450129 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:33:44.450165 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:44 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:44 DEBUG : : Looking for writers 2024/04/25 05:33:44 DEBUG : >WaitForWriters: 2024/04/25 05:33:44 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleFlushRead (2.95s) === RUN TestRWFileHandleReleaseRead run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:45 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:45 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:45 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:45 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:45 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:45 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:45 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:33:47.322887 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:33:47.322920 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:33:47 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:47 DEBUG : : Looking for writers 2024/04/25 05:33:47 DEBUG : >WaitForWriters: 2024/04/25 05:33:47 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleReleaseRead (2.79s) === RUN TestRWFileHandleMethodsWrite run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:33:48 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:33:48 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:33:48 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:48 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:48 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:48 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:33:48 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:33:48 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:33:49 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:33:49 DEBUG : file1: newRWFileHandle: 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): openPending: 2024/04/25 05:33:49 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:33:49 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): >openPending: err= 2024/04/25 05:33:49 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:33:49 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:33:49 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:33:49 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): _writeAt: size=5, off=0 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): >_writeAt: n=5, err= 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): _writeAt: size=7, off=5 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): >_writeAt: n=7, err= 2024/04/25 05:33:49 DEBUG : file1: vfs cache: truncate to size=11 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): close: 2024/04/25 05:33:49 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:33:49.010693898 +0000 UTC m=+131.643424117 2024/04/25 05:33:49 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): >close: err= 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): close: 2024/04/25 05:33:49 DEBUG : file1(0xc0007a4780): >close: err=file already closed 2024/04/25 05:33:49 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:33:49 DEBUG : : Looking for writers 2024/04/25 05:33:49 DEBUG : file1: reading active writers 2024/04/25 05:33:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:33:49 DEBUG : : Looking for writers 2024/04/25 05:33:49 DEBUG : file1: reading active writers 2024/04/25 05:33:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:33:49 DEBUG : : Looking for writers 2024/04/25 05:33:49 DEBUG : file1: reading active writers 2024/04/25 05:33:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:33:49 DEBUG : : Looking for writers 2024/04/25 05:33:49 DEBUG : file1: reading active writers 2024/04/25 05:33:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:33:49 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:33:49 DEBUG : : Looking for writers 2024/04/25 05:33:49 DEBUG : file1: reading active writers 2024/04/25 05:33:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:33:49 DEBUG : : Looking for writers 2024/04/25 05:33:49 DEBUG : file1: reading active writers 2024/04/25 05:33:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:33:49 DEBUG : : Looking for writers 2024/04/25 05:33:49 DEBUG : file1: reading active writers 2024/04/25 05:33:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:33:49.748113 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:33:49.748135 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:33:49 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:33:49 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:33:49 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:33:50 DEBUG : : Looking for writers 2024/04/25 05:33:50 DEBUG : file1: reading active writers 2024/04/25 05:33:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33:50.674776 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:33:50.674810 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:33:51 DEBUG : : Looking for writers 2024/04/25 05:33:51 DEBUG : file1: reading active writers 2024/04/25 05:33:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33:51 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:33:51 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:33:52 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:33:52 DEBUG : : Looking for writers 2024/04/25 05:33:52 DEBUG : file1: reading active writers 2024/04/25 05:33:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33:52.821775 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:33:52.821811 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:33:53 DEBUG : : Looking for writers 2024/04/25 05:33:53 DEBUG : file1: reading active writers 2024/04/25 05:33:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33: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:33:53 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:33:54 DEBUG : : Looking for writers 2024/04/25 05:33:54 DEBUG : file1: reading active writers 2024/04/25 05:33:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33:54 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:33:55 DEBUG : : Looking for writers 2024/04/25 05:33:55 DEBUG : file1: reading active writers 2024/04/25 05:33:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33:55.483776 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:33:55.483813 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:33:56 DEBUG : : Looking for writers 2024/04/25 05:33:56 DEBUG : file1: reading active writers 2024/04/25 05:33:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33: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:33:56 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:33:57 DEBUG : : Looking for writers 2024/04/25 05:33:57 DEBUG : file1: reading active writers 2024/04/25 05:33:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33:58 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:33:58 DEBUG : : Looking for writers 2024/04/25 05:33:58 DEBUG : file1: reading active writers 2024/04/25 05:33:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33:58.771051 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:33:58.771075 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:33:59 DEBUG : : Looking for writers 2024/04/25 05:33:59 DEBUG : file1: reading active writers 2024/04/25 05:33:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:33: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:33:59 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:34:00 DEBUG : : Looking for writers 2024/04/25 05:34:00 DEBUG : file1: reading active writers 2024/04/25 05:34:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:01 DEBUG : : Looking for writers 2024/04/25 05:34:01 DEBUG : file1: reading active writers 2024/04/25 05:34:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:02 DEBUG : : Looking for writers 2024/04/25 05:34:02 DEBUG : file1: reading active writers 2024/04/25 05:34:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:03 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:34:03 DEBUG : : Looking for writers 2024/04/25 05:34:03 DEBUG : file1: reading active writers 2024/04/25 05:34:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:03.750289 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:34:03.750316 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:34:04 DEBUG : : Looking for writers 2024/04/25 05:34:04 DEBUG : file1: reading active writers 2024/04/25 05:34:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34: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:34:04 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:34:05 DEBUG : : Looking for writers 2024/04/25 05:34:05 DEBUG : file1: reading active writers 2024/04/25 05:34:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:06 DEBUG : : Looking for writers 2024/04/25 05:34:06 DEBUG : file1: reading active writers 2024/04/25 05:34:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:07 DEBUG : : Looking for writers 2024/04/25 05:34:07 DEBUG : file1: reading active writers 2024/04/25 05:34:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:08 DEBUG : : Looking for writers 2024/04/25 05:34:08 DEBUG : file1: reading active writers 2024/04/25 05:34:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:09 DEBUG : : Looking for writers 2024/04/25 05:34:09 DEBUG : file1: reading active writers 2024/04/25 05:34:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:10 DEBUG : : Looking for writers 2024/04/25 05:34:10 DEBUG : file1: reading active writers 2024/04/25 05:34:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:11 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:34:11 DEBUG : : Looking for writers 2024/04/25 05:34:11 DEBUG : file1: reading active writers 2024/04/25 05:34:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:11.955640 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:34:11.955670 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:34:12 DEBUG : : Looking for writers 2024/04/25 05:34:12 DEBUG : file1: reading active writers 2024/04/25 05:34:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:13 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:34:13 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:34:13 DEBUG : : Looking for writers 2024/04/25 05:34:13 DEBUG : file1: reading active writers 2024/04/25 05:34:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:14 DEBUG : : Looking for writers 2024/04/25 05:34:14 DEBUG : file1: reading active writers 2024/04/25 05:34:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:15 DEBUG : : Looking for writers 2024/04/25 05:34:15 DEBUG : file1: reading active writers 2024/04/25 05:34:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:16 DEBUG : : Looking for writers 2024/04/25 05:34:16 DEBUG : file1: reading active writers 2024/04/25 05:34:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:17 DEBUG : : Looking for writers 2024/04/25 05:34:17 DEBUG : file1: reading active writers 2024/04/25 05:34:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:18 DEBUG : : Looking for writers 2024/04/25 05:34:18 DEBUG : file1: reading active writers 2024/04/25 05:34:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:19 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc000a4a4b0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0000cf508 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824634570048} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943875274763611402 ext:131643424117 loc:0x3a135e0} ATime:{wall:13943875274763626350 ext:131643439075 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:34:19 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:34:25 DEBUG : file1: vfs cache: starting upload fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 2024/04/25 05:34:34.966674 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:34:34.966705 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:34: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:34:35 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:34:48 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:34:48 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:34:54 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:34:54 DEBUG : : Looking for writers 2024/04/25 05:34:54 DEBUG : file1: reading active writers 2024/04/25 05:34:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:34:54 DEBUG : : Looking for writers 2024/04/25 05:34:54 DEBUG : file1: reading active writers 2024/04/25 05:34:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:34:54 DEBUG : : Looking for writers 2024/04/25 05:34:54 DEBUG : file1: reading active writers 2024/04/25 05:34:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:34:54 DEBUG : : Looking for writers 2024/04/25 05:34:54 DEBUG : file1: reading active writers 2024/04/25 05:34:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:34:54 DEBUG : : Looking for writers 2024/04/25 05:34:54 DEBUG : file1: reading active writers 2024/04/25 05:34:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:34:54 DEBUG : : Looking for writers 2024/04/25 05:34:54 DEBUG : file1: reading active writers 2024/04/25 05:34:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:34:55 DEBUG : : Looking for writers 2024/04/25 05:34:55 DEBUG : file1: reading active writers 2024/04/25 05:34:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:34:55 DEBUG : : Looking for writers 2024/04/25 05:34:55 DEBUG : file1: reading active writers 2024/04/25 05:34:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:56 DEBUG : : Looking for writers 2024/04/25 05:34:56 DEBUG : file1: reading active writers 2024/04/25 05:34:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:57 DEBUG : : Looking for writers 2024/04/25 05:34:57 DEBUG : file1: reading active writers 2024/04/25 05:34:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:58 DEBUG : : Looking for writers 2024/04/25 05:34:58 DEBUG : file1: reading active writers 2024/04/25 05:34:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:34:59 DEBUG : : Looking for writers 2024/04/25 05:34:59 DEBUG : file1: reading active writers 2024/04/25 05:34:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:00 DEBUG : : Looking for writers 2024/04/25 05:35:00 DEBUG : file1: reading active writers 2024/04/25 05:35:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:01 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:35:01 DEBUG : : Looking for writers 2024/04/25 05:35:01 DEBUG : file1: reading active writers 2024/04/25 05:35:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:02 DEBUG : : Looking for writers 2024/04/25 05:35:02 DEBUG : file1: reading active writers 2024/04/25 05:35:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:03 DEBUG : : Looking for writers 2024/04/25 05:35:03 DEBUG : file1: reading active writers 2024/04/25 05:35:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:04 DEBUG : : Looking for writers 2024/04/25 05:35:04 DEBUG : file1: reading active writers 2024/04/25 05:35:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:05 DEBUG : : Looking for writers 2024/04/25 05:35:05 DEBUG : file1: reading active writers 2024/04/25 05:35:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:06 DEBUG : : Looking for writers 2024/04/25 05:35:06 DEBUG : file1: reading active writers 2024/04/25 05:35:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:07 DEBUG : : Looking for writers 2024/04/25 05:35:07 DEBUG : file1: reading active writers 2024/04/25 05:35:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:08 DEBUG : : Looking for writers 2024/04/25 05:35:08 DEBUG : file1: reading active writers 2024/04/25 05:35:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:09 DEBUG : : Looking for writers 2024/04/25 05:35:09 DEBUG : file1: reading active writers 2024/04/25 05:35:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:10 DEBUG : : Looking for writers 2024/04/25 05:35:10 DEBUG : file1: reading active writers 2024/04/25 05:35:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:11 DEBUG : : Looking for writers 2024/04/25 05:35:11 DEBUG : file1: reading active writers 2024/04/25 05:35:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:12 DEBUG : : Looking for writers 2024/04/25 05:35:12 DEBUG : file1: reading active writers 2024/04/25 05:35:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:13 DEBUG : : Looking for writers 2024/04/25 05:35:13 DEBUG : file1: reading active writers 2024/04/25 05:35:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:14 DEBUG : : Looking for writers 2024/04/25 05:35:14 DEBUG : file1: reading active writers 2024/04/25 05:35:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:15 DEBUG : : Looking for writers 2024/04/25 05:35:15 DEBUG : file1: reading active writers 2024/04/25 05:35:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:16.209017 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:35:16.209050 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:35:16 DEBUG : : Looking for writers 2024/04/25 05:35:16 DEBUG : file1: reading active writers 2024/04/25 05:35:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:17 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:35:17 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:35:17 DEBUG : : Looking for writers 2024/04/25 05:35:17 DEBUG : file1: reading active writers 2024/04/25 05:35:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:18 DEBUG : : Looking for writers 2024/04/25 05:35:18 DEBUG : file1: reading active writers 2024/04/25 05:35:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:19 DEBUG : : Looking for writers 2024/04/25 05:35:19 DEBUG : file1: reading active writers 2024/04/25 05:35:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:20 DEBUG : : Looking for writers 2024/04/25 05:35:20 DEBUG : file1: reading active writers 2024/04/25 05:35:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:21 DEBUG : : Looking for writers 2024/04/25 05:35:21 DEBUG : file1: reading active writers 2024/04/25 05:35:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:22 DEBUG : : Looking for writers 2024/04/25 05:35:22 DEBUG : file1: reading active writers 2024/04/25 05:35:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:23 DEBUG : : Looking for writers 2024/04/25 05:35:23 DEBUG : file1: reading active writers 2024/04/25 05:35:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:24 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc000a4a4b0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc0000cf508 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824634570048} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943875274763611402 ext:131643424117 loc:0x3a135e0} ATime:{wall:13943875274763626350 ext:131643439075 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:35:24 DEBUG : >WaitForWriters: 2024/04/25 05:35:24 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleMethodsWrite (96.47s) === RUN TestRWFileHandleWriteAt run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:35:25 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:35:25 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:35:25 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:35:25 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:35:25 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:35:25 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:35:25 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:35:25 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:35:25 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:35:25 DEBUG : file1: newRWFileHandle: 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): openPending: 2024/04/25 05:35:25 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:35:25 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): >openPending: err= 2024/04/25 05:35:25 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:35:25 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:35:25 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:35:25 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): _writeAt: size=7, off=0 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): >_writeAt: n=7, err= 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): _writeAt: size=6, off=5 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): >_writeAt: n=6, err= 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): close: 2024/04/25 05:35:25 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:35:25.454721126 +0000 UTC m=+228.087451315 2024/04/25 05:35:25 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): >close: err= 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): _writeAt: size=5, off=0 2024/04/25 05:35:25 DEBUG : file1(0xc001df0100): >_writeAt: n=0, err=file already closed 2024/04/25 05:35:25 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:35:25 DEBUG : : Looking for writers 2024/04/25 05:35:25 DEBUG : file1: reading active writers 2024/04/25 05:35:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:35:25 DEBUG : : Looking for writers 2024/04/25 05:35:25 DEBUG : file1: reading active writers 2024/04/25 05:35:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:35:25 DEBUG : : Looking for writers 2024/04/25 05:35:25 DEBUG : file1: reading active writers 2024/04/25 05:35:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:35:25 DEBUG : : Looking for writers 2024/04/25 05:35:25 DEBUG : file1: reading active writers 2024/04/25 05:35:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:35:25 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:35:25 DEBUG : : Looking for writers 2024/04/25 05:35:25 DEBUG : file1: reading active writers 2024/04/25 05:35:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:35:25 DEBUG : : Looking for writers 2024/04/25 05:35:25 DEBUG : file1: reading active writers 2024/04/25 05:35:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:35:26 DEBUG : : Looking for writers 2024/04/25 05:35:26 DEBUG : file1: reading active writers 2024/04/25 05:35:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:35:26.232652 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:35:26.232677 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:35:26 DEBUG : : Looking for writers 2024/04/25 05:35:26 DEBUG : file1: reading active writers 2024/04/25 05:35:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:27 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:35:27 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:35:27 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:35:27 DEBUG : : Looking for writers 2024/04/25 05:35:27 DEBUG : file1: reading active writers 2024/04/25 05:35:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:28.147584 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:35:28.147609 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:35:28 DEBUG : : Looking for writers 2024/04/25 05:35:28 DEBUG : file1: reading active writers 2024/04/25 05:35:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:29 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:35:29 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:35:29 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:35:29 DEBUG : : Looking for writers 2024/04/25 05:35:29 DEBUG : file1: reading active writers 2024/04/25 05:35:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:30.207200 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:35:30.207233 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:35:30 DEBUG : : Looking for writers 2024/04/25 05:35:30 DEBUG : file1: reading active writers 2024/04/25 05:35:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:31 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:35:31 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:35:31 DEBUG : : Looking for writers 2024/04/25 05:35:31 DEBUG : file1: reading active writers 2024/04/25 05:35:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:32 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:35:32.721663 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:35:32.721704 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:35:32 DEBUG : : Looking for writers 2024/04/25 05:35:32 DEBUG : file1: reading active writers 2024/04/25 05:35:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:33 DEBUG : : Looking for writers 2024/04/25 05:35:33 DEBUG : file1: reading active writers 2024/04/25 05:35:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35: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:35:33 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:35:34 DEBUG : : Looking for writers 2024/04/25 05:35:34 DEBUG : file1: reading active writers 2024/04/25 05:35:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:35 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:35:35 DEBUG : : Looking for writers 2024/04/25 05:35:35 DEBUG : file1: reading active writers 2024/04/25 05:35:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:36.118940 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:35:36.118985 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:35:36 DEBUG : : Looking for writers 2024/04/25 05:35:36 DEBUG : file1: reading active writers 2024/04/25 05:35:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:37 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:35:37 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:35:37 DEBUG : : Looking for writers 2024/04/25 05:35:37 DEBUG : file1: reading active writers 2024/04/25 05:35:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:38 DEBUG : : Looking for writers 2024/04/25 05:35:38 DEBUG : file1: reading active writers 2024/04/25 05:35:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:39 DEBUG : : Looking for writers 2024/04/25 05:35:39 DEBUG : file1: reading active writers 2024/04/25 05:35:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:40 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:35:40 DEBUG : : Looking for writers 2024/04/25 05:35:40 DEBUG : file1: reading active writers 2024/04/25 05:35:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:41.210924 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:35:41.210950 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:35:41 DEBUG : : Looking for writers 2024/04/25 05:35:41 DEBUG : file1: reading active writers 2024/04/25 05:35:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35: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:35:42 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:35:42 DEBUG : : Looking for writers 2024/04/25 05:35:42 DEBUG : file1: reading active writers 2024/04/25 05:35:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:43 DEBUG : : Looking for writers 2024/04/25 05:35:43 DEBUG : file1: reading active writers 2024/04/25 05:35:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:44 DEBUG : : Looking for writers 2024/04/25 05:35:44 DEBUG : file1: reading active writers 2024/04/25 05:35:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:45 DEBUG : : Looking for writers 2024/04/25 05:35:45 DEBUG : file1: reading active writers 2024/04/25 05:35:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:46 DEBUG : : Looking for writers 2024/04/25 05:35:46 DEBUG : file1: reading active writers 2024/04/25 05:35:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:47 DEBUG : : Looking for writers 2024/04/25 05:35:47 DEBUG : file1: reading active writers 2024/04/25 05:35:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:48 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:35:48 DEBUG : : Looking for writers 2024/04/25 05:35:48 DEBUG : file1: reading active writers 2024/04/25 05:35:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:49.321758 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:35:49.321787 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:35:49 DEBUG : : Looking for writers 2024/04/25 05:35:49 DEBUG : file1: reading active writers 2024/04/25 05:35:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35: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:35:50 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:35:50 DEBUG : : Looking for writers 2024/04/25 05:35:50 DEBUG : file1: reading active writers 2024/04/25 05:35:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:51 DEBUG : : Looking for writers 2024/04/25 05:35:51 DEBUG : file1: reading active writers 2024/04/25 05:35:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:52 DEBUG : : Looking for writers 2024/04/25 05:35:52 DEBUG : file1: reading active writers 2024/04/25 05:35:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:53 DEBUG : : Looking for writers 2024/04/25 05:35:53 DEBUG : file1: reading active writers 2024/04/25 05:35:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:54 DEBUG : : Looking for writers 2024/04/25 05:35:54 DEBUG : file1: reading active writers 2024/04/25 05:35:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:35:55 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc001c0e000 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000d8f408 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824647939136} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943875378286853734 ext:228087451315 loc:0x3a135e0} ATime:{wall:13943875378286868001 ext:228087465582 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:35:55 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:36:03 DEBUG : file1: vfs cache: starting upload fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 2024/04/25 05:36:11.586529 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:36:11.586566 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:36: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:36:12 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:36:25 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:36:25 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:36:31 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:36:31 DEBUG : : Looking for writers 2024/04/25 05:36:31 DEBUG : file1: reading active writers 2024/04/25 05:36:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:36:31 DEBUG : : Looking for writers 2024/04/25 05:36:31 DEBUG : file1: reading active writers 2024/04/25 05:36:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:36:31 DEBUG : : Looking for writers 2024/04/25 05:36:31 DEBUG : file1: reading active writers 2024/04/25 05:36:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:36:31 DEBUG : : Looking for writers 2024/04/25 05:36:31 DEBUG : file1: reading active writers 2024/04/25 05:36:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:36:31 DEBUG : : Looking for writers 2024/04/25 05:36:31 DEBUG : file1: reading active writers 2024/04/25 05:36:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:36:31 DEBUG : : Looking for writers 2024/04/25 05:36:31 DEBUG : file1: reading active writers 2024/04/25 05:36:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:36:32 DEBUG : : Looking for writers 2024/04/25 05:36:32 DEBUG : file1: reading active writers 2024/04/25 05:36:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:36:32 DEBUG : : Looking for writers 2024/04/25 05:36:32 DEBUG : file1: reading active writers 2024/04/25 05:36:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:33 DEBUG : : Looking for writers 2024/04/25 05:36:33 DEBUG : file1: reading active writers 2024/04/25 05:36:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:34 DEBUG : : Looking for writers 2024/04/25 05:36:34 DEBUG : file1: reading active writers 2024/04/25 05:36:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:35 DEBUG : : Looking for writers 2024/04/25 05:36:35 DEBUG : file1: reading active writers 2024/04/25 05:36:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:36 DEBUG : : Looking for writers 2024/04/25 05:36:36 DEBUG : file1: reading active writers 2024/04/25 05:36:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:37 DEBUG : : Looking for writers 2024/04/25 05:36:37 DEBUG : file1: reading active writers 2024/04/25 05:36:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:38 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:36:38 DEBUG : : Looking for writers 2024/04/25 05:36:38 DEBUG : file1: reading active writers 2024/04/25 05:36:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:39 DEBUG : : Looking for writers 2024/04/25 05:36:39 DEBUG : file1: reading active writers 2024/04/25 05:36:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:40 DEBUG : : Looking for writers 2024/04/25 05:36:40 DEBUG : file1: reading active writers 2024/04/25 05:36:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:41 DEBUG : : Looking for writers 2024/04/25 05:36:41 DEBUG : file1: reading active writers 2024/04/25 05:36:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:42 DEBUG : : Looking for writers 2024/04/25 05:36:42 DEBUG : file1: reading active writers 2024/04/25 05:36:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:43 DEBUG : : Looking for writers 2024/04/25 05:36:43 DEBUG : file1: reading active writers 2024/04/25 05:36:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:44 DEBUG : : Looking for writers 2024/04/25 05:36:44 DEBUG : file1: reading active writers 2024/04/25 05:36:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:45 DEBUG : : Looking for writers 2024/04/25 05:36:45 DEBUG : file1: reading active writers 2024/04/25 05:36:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:46 DEBUG : : Looking for writers 2024/04/25 05:36:46 DEBUG : file1: reading active writers 2024/04/25 05:36:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:47 DEBUG : : Looking for writers 2024/04/25 05:36:47 DEBUG : file1: reading active writers 2024/04/25 05:36:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:48 DEBUG : : Looking for writers 2024/04/25 05:36:48 DEBUG : file1: reading active writers 2024/04/25 05:36:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:49 DEBUG : : Looking for writers 2024/04/25 05:36:49 DEBUG : file1: reading active writers 2024/04/25 05:36:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:50 DEBUG : : Looking for writers 2024/04/25 05:36:50 DEBUG : file1: reading active writers 2024/04/25 05:36:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:51 DEBUG : : Looking for writers 2024/04/25 05:36:51 DEBUG : file1: reading active writers 2024/04/25 05:36:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:52 DEBUG : : Looking for writers 2024/04/25 05:36:52 DEBUG : file1: reading active writers 2024/04/25 05:36:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:52.924334 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:36:52.924377 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:36:53 DEBUG : : Looking for writers 2024/04/25 05:36:53 DEBUG : file1: reading active writers 2024/04/25 05:36:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36: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:36:54 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:36:54 DEBUG : : Looking for writers 2024/04/25 05:36:54 DEBUG : file1: reading active writers 2024/04/25 05:36:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:55 DEBUG : : Looking for writers 2024/04/25 05:36:55 DEBUG : file1: reading active writers 2024/04/25 05:36:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:56 DEBUG : : Looking for writers 2024/04/25 05:36:56 DEBUG : file1: reading active writers 2024/04/25 05:36:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:57 DEBUG : : Looking for writers 2024/04/25 05:36:57 DEBUG : file1: reading active writers 2024/04/25 05:36:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:58 DEBUG : : Looking for writers 2024/04/25 05:36:58 DEBUG : file1: reading active writers 2024/04/25 05:36:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:36:59 DEBUG : : Looking for writers 2024/04/25 05:36:59 DEBUG : file1: reading active writers 2024/04/25 05:36:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:00 DEBUG : : Looking for writers 2024/04/25 05:37:00 DEBUG : file1: reading active writers 2024/04/25 05:37:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:01 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc001c0e000 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000d8f408 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824647939136} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943875378286853734 ext:228087451315 loc:0x3a135e0} ATime:{wall:13943875378286868001 ext:228087465582 loc:0x3a135e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:37:01 DEBUG : >WaitForWriters: 2024/04/25 05:37:01 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteAt (96.98s) === RUN TestRWFileHandleWriteNoWrite run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:37:02 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:37:02 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:37:02 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:37:02 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:37:02 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:37:02 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:37:02 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:37:02 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:37:02 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:37:02 DEBUG : file1: newRWFileHandle: 2024/04/25 05:37:02 DEBUG : file1(0xc000ad1900): openPending: 2024/04/25 05:37:02 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:37:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:37:02 DEBUG : file1(0xc000ad1900): >openPending: err= 2024/04/25 05:37:02 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:37:02 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:37:02 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:37:02 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:37:02 DEBUG : file1(0xc000ad1900): close: 2024/04/25 05:37:02 DEBUG : file1: vfs cache: setting modification time to 2024-04-25 05:37:02.420351438 +0000 UTC m=+325.053081647 2024/04/25 05:37:02 INFO : file1: vfs cache: queuing for upload in 100ms 2024/04/25 05:37:02 DEBUG : file1(0xc000ad1900): >close: err= 2024/04/25 05:37:02 DEBUG : file2: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2024/04/25 05:37:02 DEBUG : file2: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2024/04/25 05:37:02 DEBUG : file2: newRWFileHandle: 2024/04/25 05:37:02 DEBUG : file2(0xc000ad1a00): openPending: 2024/04/25 05:37:02 DEBUG : file2: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:37:02 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:37:02 DEBUG : file2(0xc000ad1a00): >openPending: err= 2024/04/25 05:37:02 DEBUG : file2: >newRWFileHandle: err= 2024/04/25 05:37:02 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:37:02 DEBUG : file2: >Open: fd=file2 (rw), err= 2024/04/25 05:37:02 DEBUG : file2: >OpenFile: fd=file2 (rw), err= 2024/04/25 05:37:02 DEBUG : file2(0xc000ad1a00): RWFileHandle.Flush 2024/04/25 05:37:02 DEBUG : file2(0xc000ad1a00): RWFileHandle.Release 2024/04/25 05:37:02 DEBUG : file2(0xc000ad1a00): close: 2024/04/25 05:37:02 DEBUG : file2: vfs cache: setting modification time to 2024-04-25 05:37:02.421222372 +0000 UTC m=+325.053952580 2024/04/25 05:37:02 INFO : file2: vfs cache: queuing for upload in 100ms 2024/04/25 05:37:02 DEBUG : file2(0xc000ad1a00): >close: err= 2024/04/25 05:37:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:37:02 DEBUG : : Looking for writers 2024/04/25 05:37:02 DEBUG : file1: reading active writers 2024/04/25 05:37:02 DEBUG : file2: reading active writers 2024/04/25 05:37:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 10ms 2024/04/25 05:37:02 DEBUG : : Looking for writers 2024/04/25 05:37:02 DEBUG : file1: reading active writers 2024/04/25 05:37:02 DEBUG : file2: reading active writers 2024/04/25 05:37:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 20ms 2024/04/25 05:37:02 DEBUG : : Looking for writers 2024/04/25 05:37:02 DEBUG : file1: reading active writers 2024/04/25 05:37:02 DEBUG : file2: reading active writers 2024/04/25 05:37:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 40ms 2024/04/25 05:37:02 DEBUG : : Looking for writers 2024/04/25 05:37:02 DEBUG : file1: reading active writers 2024/04/25 05:37:02 DEBUG : file2: reading active writers 2024/04/25 05:37:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 80ms 2024/04/25 05:37:02 DEBUG : file2: vfs cache: starting upload 2024/04/25 05:37:02 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:37:02 DEBUG : : Looking for writers 2024/04/25 05:37:02 DEBUG : file1: reading active writers 2024/04/25 05:37:02 DEBUG : file2: reading active writers 2024/04/25 05:37:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 160ms 2024/04/25 05:37:02 DEBUG : : Looking for writers 2024/04/25 05:37:02 DEBUG : file1: reading active writers 2024/04/25 05:37:02 DEBUG : file2: reading active writers 2024/04/25 05:37:02 DEBUG : Still 0 writers active and 2 cache items in use, waiting 320ms 2024/04/25 05:37:03 DEBUG : : Looking for writers 2024/04/25 05:37:03 DEBUG : file1: reading active writers 2024/04/25 05:37:03 DEBUG : file2: reading active writers 2024/04/25 05:37:03 DEBUG : Still 0 writers active and 2 cache items in use, waiting 640ms 2024/04/25 05:37:03.206720 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:37:03.206756 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:37:03 DEBUG : file2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/04/25 05:37:03 INFO : file2: Copied (new) 2024/04/25 05:37:03 DEBUG : file2: vfs cache: fingerprint now "0,2024-04-25 05:37:02.421222372 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709" 2024/04/25 05:37:03 DEBUG : file2: vfs cache: writeback object to VFS layer 2024/04/25 05:37:03 DEBUG : : Added virtual directory entry vAddFile: "file2" 2024/04/25 05:37:03 INFO : file2: vfs cache: upload succeeded try #1 2024/04/25 05:37:03 DEBUG : : Looking for writers 2024/04/25 05:37:03 DEBUG : file2: reading active writers 2024/04/25 05:37:03 DEBUG : file1: reading active writers 2024/04/25 05:37:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37: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:37:04 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:37:04 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:37:04 DEBUG : : Looking for writers 2024/04/25 05:37:04 DEBUG : file1: reading active writers 2024/04/25 05:37:04 DEBUG : file2: reading active writers 2024/04/25 05:37:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:05.182290 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:37:05.182318 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:37:05 DEBUG : : Looking for writers 2024/04/25 05:37:05 DEBUG : file1: reading active writers 2024/04/25 05:37:05 DEBUG : file2: reading active writers 2024/04/25 05:37:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37: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:37:06 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:37:06 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:37:06 DEBUG : : Looking for writers 2024/04/25 05:37:06 DEBUG : file1: reading active writers 2024/04/25 05:37:06 DEBUG : file2: reading active writers 2024/04/25 05:37:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:07.452988 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:37:07.453014 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:37:07 DEBUG : : Looking for writers 2024/04/25 05:37:07 DEBUG : file1: reading active writers 2024/04/25 05:37:07 DEBUG : file2: reading active writers 2024/04/25 05:37:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:08 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:37:08 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:37:08 DEBUG : : Looking for writers 2024/04/25 05:37:08 DEBUG : file1: reading active writers 2024/04/25 05:37:08 DEBUG : file2: reading active writers 2024/04/25 05:37:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:09 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:37:09 DEBUG : : Looking for writers 2024/04/25 05:37:09 DEBUG : file1: reading active writers 2024/04/25 05:37:09 DEBUG : file2: reading active writers 2024/04/25 05:37:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:10.012315 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:37:10.012346 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:37:10 DEBUG : : Looking for writers 2024/04/25 05:37:10 DEBUG : file1: reading active writers 2024/04/25 05:37:10 DEBUG : file2: reading active writers 2024/04/25 05:37:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:11 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:37:11 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:37:11 DEBUG : : Looking for writers 2024/04/25 05:37:11 DEBUG : file1: reading active writers 2024/04/25 05:37:11 DEBUG : file2: reading active writers 2024/04/25 05:37:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:12 DEBUG : : Looking for writers 2024/04/25 05:37:12 DEBUG : file1: reading active writers 2024/04/25 05:37:12 DEBUG : file2: reading active writers 2024/04/25 05:37:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:12 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:37:13.490630 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:37:13.490659 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:37:13 DEBUG : : Looking for writers 2024/04/25 05:37:13 DEBUG : file1: reading active writers 2024/04/25 05:37:13 DEBUG : file2: reading active writers 2024/04/25 05:37:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:14 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:37:14 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:37:14 DEBUG : : Looking for writers 2024/04/25 05:37:14 DEBUG : file1: reading active writers 2024/04/25 05:37:14 DEBUG : file2: reading active writers 2024/04/25 05:37:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:15 DEBUG : : Looking for writers 2024/04/25 05:37:15 DEBUG : file1: reading active writers 2024/04/25 05:37:15 DEBUG : file2: reading active writers 2024/04/25 05:37:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:16 DEBUG : : Looking for writers 2024/04/25 05:37:16 DEBUG : file1: reading active writers 2024/04/25 05:37:16 DEBUG : file2: reading active writers 2024/04/25 05:37:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:17 DEBUG : : Looking for writers 2024/04/25 05:37:17 DEBUG : file1: reading active writers 2024/04/25 05:37:17 DEBUG : file2: reading active writers 2024/04/25 05:37:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:17 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:37:18.444049 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:37:18.444083 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:37:18 DEBUG : : Looking for writers 2024/04/25 05:37:18 DEBUG : file1: reading active writers 2024/04/25 05:37:18 DEBUG : file2: reading active writers 2024/04/25 05:37:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37: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:37:19 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:37:19 DEBUG : : Looking for writers 2024/04/25 05:37:19 DEBUG : file1: reading active writers 2024/04/25 05:37:19 DEBUG : file2: reading active writers 2024/04/25 05:37:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:20 DEBUG : : Looking for writers 2024/04/25 05:37:20 DEBUG : file1: reading active writers 2024/04/25 05:37:20 DEBUG : file2: reading active writers 2024/04/25 05:37:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:21 DEBUG : : Looking for writers 2024/04/25 05:37:21 DEBUG : file1: reading active writers 2024/04/25 05:37:21 DEBUG : file2: reading active writers 2024/04/25 05:37:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:22 DEBUG : : Looking for writers 2024/04/25 05:37:22 DEBUG : file1: reading active writers 2024/04/25 05:37:22 DEBUG : file2: reading active writers 2024/04/25 05:37:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:23 DEBUG : : Looking for writers 2024/04/25 05:37:23 DEBUG : file2: reading active writers 2024/04/25 05:37:23 DEBUG : file1: reading active writers 2024/04/25 05:37:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:24 DEBUG : : Looking for writers 2024/04/25 05:37:24 DEBUG : file1: reading active writers 2024/04/25 05:37:24 DEBUG : file2: reading active writers 2024/04/25 05:37:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:25 DEBUG : : Looking for writers 2024/04/25 05:37:25 DEBUG : file1: reading active writers 2024/04/25 05:37:25 DEBUG : file2: reading active writers 2024/04/25 05:37:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:25 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:37:26.646438 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:37:26.646470 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:37:26 DEBUG : : Looking for writers 2024/04/25 05:37:26 DEBUG : file2: reading active writers 2024/04/25 05:37:26 DEBUG : file1: reading active writers 2024/04/25 05:37:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:27 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:37:27 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:37:27 DEBUG : : Looking for writers 2024/04/25 05:37:27 DEBUG : file1: reading active writers 2024/04/25 05:37:27 DEBUG : file2: reading active writers 2024/04/25 05:37:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:28 DEBUG : : Looking for writers 2024/04/25 05:37:28 DEBUG : file1: reading active writers 2024/04/25 05:37:28 DEBUG : file2: reading active writers 2024/04/25 05:37:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:29 DEBUG : : Looking for writers 2024/04/25 05:37:29 DEBUG : file1: reading active writers 2024/04/25 05:37:29 DEBUG : file2: reading active writers 2024/04/25 05:37:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:30 DEBUG : : Looking for writers 2024/04/25 05:37:30 DEBUG : file1: reading active writers 2024/04/25 05:37:30 DEBUG : file2: reading active writers 2024/04/25 05:37:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:31 DEBUG : : Looking for writers 2024/04/25 05:37:31 DEBUG : file1: reading active writers 2024/04/25 05:37:31 DEBUG : file2: reading active writers 2024/04/25 05:37:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:37:32 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc000a4a3c0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000d8ec08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824647937088} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943875482405440974 ext:325053081647 loc:0x3a135e0} ATime:{wall:13943875482405634898 ext:325053275561 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, "file2": &{c:0xc000a4a3c0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000d8ed08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824647937344} name:file2 opens:0 downloaders: o:0xc000784dc0 fd: info:{ModTime:{wall:13943875482406311908 ext:325053952580 loc:0x3a135e0} ATime:{wall:13943875482406438274 ext:325054078948 loc:0x3a135e0} Size:0 Rs:[] Fingerprint:0,2024-04-25 05:37:02.421222372 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709 Dirty:false} writeBackID:2 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:37:32 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:37:40 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:37:49.192588 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:37:49.192630 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:37: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:37:50 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:38:02 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file2 not removed, freed 0 bytes 2024/04/25 05:38:02 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes 2024/04/25 05:38:02 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:38:10 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:38:10 DEBUG : : Looking for writers 2024/04/25 05:38:10 DEBUG : file1: reading active writers 2024/04/25 05:38:10 DEBUG : file2: reading active writers 2024/04/25 05:38:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:38:10 DEBUG : : Looking for writers 2024/04/25 05:38:10 DEBUG : file1: reading active writers 2024/04/25 05:38:10 DEBUG : file2: reading active writers 2024/04/25 05:38:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:38:10 DEBUG : : Looking for writers 2024/04/25 05:38:10 DEBUG : file1: reading active writers 2024/04/25 05:38:10 DEBUG : file2: reading active writers 2024/04/25 05:38:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:38:10 DEBUG : : Looking for writers 2024/04/25 05:38:10 DEBUG : file1: reading active writers 2024/04/25 05:38:10 DEBUG : file2: reading active writers 2024/04/25 05:38:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:38:10 DEBUG : : Looking for writers 2024/04/25 05:38:10 DEBUG : file1: reading active writers 2024/04/25 05:38:10 DEBUG : file2: reading active writers 2024/04/25 05:38:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:38:10 DEBUG : : Looking for writers 2024/04/25 05:38:10 DEBUG : file1: reading active writers 2024/04/25 05:38:10 DEBUG : file2: reading active writers 2024/04/25 05:38:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:38:11 DEBUG : : Looking for writers 2024/04/25 05:38:11 DEBUG : file1: reading active writers 2024/04/25 05:38:11 DEBUG : file2: reading active writers 2024/04/25 05:38:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:38:11 DEBUG : : Looking for writers 2024/04/25 05:38:11 DEBUG : file1: reading active writers 2024/04/25 05:38:11 DEBUG : file2: reading active writers 2024/04/25 05:38:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:12 DEBUG : : Looking for writers 2024/04/25 05:38:12 DEBUG : file1: reading active writers 2024/04/25 05:38:12 DEBUG : file2: reading active writers 2024/04/25 05:38:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:13 DEBUG : : Looking for writers 2024/04/25 05:38:13 DEBUG : file1: reading active writers 2024/04/25 05:38:13 DEBUG : file2: reading active writers 2024/04/25 05:38:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:14 DEBUG : : Looking for writers 2024/04/25 05:38:14 DEBUG : file1: reading active writers 2024/04/25 05:38:14 DEBUG : file2: reading active writers 2024/04/25 05:38:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:15 DEBUG : : Looking for writers 2024/04/25 05:38:15 DEBUG : file1: reading active writers 2024/04/25 05:38:15 DEBUG : file2: reading active writers 2024/04/25 05:38:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:15 DEBUG : file1: vfs cache: starting upload 2024/04/25 05:38:16 DEBUG : : Looking for writers 2024/04/25 05:38:16 DEBUG : file1: reading active writers 2024/04/25 05:38:16 DEBUG : file2: reading active writers 2024/04/25 05:38:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:17 DEBUG : : Looking for writers 2024/04/25 05:38:17 DEBUG : file1: reading active writers 2024/04/25 05:38:17 DEBUG : file2: reading active writers 2024/04/25 05:38:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:18 DEBUG : : Looking for writers 2024/04/25 05:38:18 DEBUG : file2: reading active writers 2024/04/25 05:38:18 DEBUG : file1: reading active writers 2024/04/25 05:38:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:19 DEBUG : : Looking for writers 2024/04/25 05:38:19 DEBUG : file2: reading active writers 2024/04/25 05:38:19 DEBUG : file1: reading active writers 2024/04/25 05:38:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:20 DEBUG : : Looking for writers 2024/04/25 05:38:20 DEBUG : file2: reading active writers 2024/04/25 05:38:20 DEBUG : file1: reading active writers 2024/04/25 05:38:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:21 DEBUG : : Looking for writers 2024/04/25 05:38:21 DEBUG : file2: reading active writers 2024/04/25 05:38:21 DEBUG : file1: reading active writers 2024/04/25 05:38:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:22 DEBUG : : Looking for writers 2024/04/25 05:38:22 DEBUG : file1: reading active writers 2024/04/25 05:38:22 DEBUG : file2: reading active writers 2024/04/25 05:38:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:23 DEBUG : : Looking for writers 2024/04/25 05:38:23 DEBUG : file1: reading active writers 2024/04/25 05:38:23 DEBUG : file2: reading active writers 2024/04/25 05:38:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:24 DEBUG : : Looking for writers 2024/04/25 05:38:24 DEBUG : file1: reading active writers 2024/04/25 05:38:24 DEBUG : file2: reading active writers 2024/04/25 05:38:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:25 DEBUG : : Looking for writers 2024/04/25 05:38:25 DEBUG : file1: reading active writers 2024/04/25 05:38:25 DEBUG : file2: reading active writers 2024/04/25 05:38:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:26 DEBUG : : Looking for writers 2024/04/25 05:38:26 DEBUG : file1: reading active writers 2024/04/25 05:38:26 DEBUG : file2: reading active writers 2024/04/25 05:38:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:27 DEBUG : : Looking for writers 2024/04/25 05:38:27 DEBUG : file1: reading active writers 2024/04/25 05:38:27 DEBUG : file2: reading active writers 2024/04/25 05:38:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:28 DEBUG : : Looking for writers 2024/04/25 05:38:28 DEBUG : file1: reading active writers 2024/04/25 05:38:28 DEBUG : file2: reading active writers 2024/04/25 05:38:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:29 DEBUG : : Looking for writers 2024/04/25 05:38:29 DEBUG : file1: reading active writers 2024/04/25 05:38:29 DEBUG : file2: reading active writers 2024/04/25 05:38:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:30 DEBUG : : Looking for writers 2024/04/25 05:38:30 DEBUG : file1: reading active writers 2024/04/25 05:38:30 DEBUG : file2: reading active writers 2024/04/25 05:38:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:30.811879 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:38:30.811905 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:38:31 DEBUG : : Looking for writers 2024/04/25 05:38:31 DEBUG : file1: reading active writers 2024/04/25 05:38:31 DEBUG : file2: reading active writers 2024/04/25 05:38:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:31 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:38:31 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:38:32 DEBUG : : Looking for writers 2024/04/25 05:38:32 DEBUG : file1: reading active writers 2024/04/25 05:38:32 DEBUG : file2: reading active writers 2024/04/25 05:38:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:33 DEBUG : : Looking for writers 2024/04/25 05:38:33 DEBUG : file1: reading active writers 2024/04/25 05:38:33 DEBUG : file2: reading active writers 2024/04/25 05:38:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:34 DEBUG : : Looking for writers 2024/04/25 05:38:34 DEBUG : file1: reading active writers 2024/04/25 05:38:34 DEBUG : file2: reading active writers 2024/04/25 05:38:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:35 DEBUG : : Looking for writers 2024/04/25 05:38:35 DEBUG : file1: reading active writers 2024/04/25 05:38:35 DEBUG : file2: reading active writers 2024/04/25 05:38:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:36 DEBUG : : Looking for writers 2024/04/25 05:38:36 DEBUG : file2: reading active writers 2024/04/25 05:38:36 DEBUG : file1: reading active writers 2024/04/25 05:38:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:37 DEBUG : : Looking for writers 2024/04/25 05:38:37 DEBUG : file1: reading active writers 2024/04/25 05:38:37 DEBUG : file2: reading active writers 2024/04/25 05:38:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:38 DEBUG : : Looking for writers 2024/04/25 05:38:38 DEBUG : file1: reading active writers 2024/04/25 05:38:38 DEBUG : file2: reading active writers 2024/04/25 05:38:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:39 DEBUG : : Looking for writers 2024/04/25 05:38:39 DEBUG : file1: reading active writers 2024/04/25 05:38:39 DEBUG : file2: reading active writers 2024/04/25 05:38:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:40 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc000a4a3c0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000d8ec08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824647937088} name:file1 opens:0 downloaders: o: fd: info:{ModTime:{wall:13943875482405440974 ext:325053081647 loc:0x3a135e0} ATime:{wall:13943875482405634898 ext:325053275561 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false}, "file2": &{c:0xc000a4a3c0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000d8ed08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824647937344} name:file2 opens:0 downloaders: o:0xc000784dc0 fd: info:{ModTime:{wall:13943875482406311908 ext:325053952580 loc:0x3a135e0} ATime:{wall:13943875482406438274 ext:325054078948 loc:0x3a135e0} Size:0 Rs:[] Fingerprint:0,2024-04-25 05:37:02.421222372 +0000 UTC,da39a3ee5e6b4b0d3255bfef95601890afd80709 Dirty:false} writeBackID:2 pendingAccesses:0 modified:false beingReset:false}, } 2024/04/25 05:38:40 DEBUG : >WaitForWriters: 2024/04/25 05:38:40 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleWriteNoWrite (100.07s) === RUN TestRWFileHandleSizeTruncateExisting run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:38:42 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:38:42 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:38:42 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:42 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:42 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:42 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:42 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:38:43.571119 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:38:43.571153 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:38:43 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:38:43 DEBUG : : Looking for writers 2024/04/25 05:38:43 DEBUG : >WaitForWriters: 2024/04/25 05:38:43 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeTruncateExisting (2.96s) === RUN TestRWFileHandleSizeCreateExisting run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:38:45 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:38:45 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:38:45 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:45 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:45 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:45 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:45 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:38:46.543180 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:38:46.543218 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:38:46 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:38:46 DEBUG : : Looking for writers 2024/04/25 05:38:46 DEBUG : >WaitForWriters: 2024/04/25 05:38:46 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileHandleSizeCreateExisting (2.72s) === RUN TestRWFileModTimeWithOpenWriters run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:38:47 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:38:47 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:38:47 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:47 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:47 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:47 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:38:47 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:38: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:38:48 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:38:48 DEBUG : file1: newRWFileHandle: 2024/04/25 05:38:48 DEBUG : file1(0xc001df0440): openPending: 2024/04/25 05:38:48 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct) 2024/04/25 05:38:48 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:38:48 DEBUG : file1(0xc001df0440): >openPending: err= 2024/04/25 05:38:48 DEBUG : file1: >newRWFileHandle: err= 2024/04/25 05:38:48 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:38:48 DEBUG : file1: >Open: fd=file1 (rw), err= 2024/04/25 05:38:48 DEBUG : file1: >OpenFile: fd=file1 (rw), err= 2024/04/25 05:38:48.795190 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:38:48.795229 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:38:48 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:38:48 DEBUG : : Looking for writers 2024/04/25 05:38:48 DEBUG : file1: reading active writers 2024/04/25 05:38:48 DEBUG : file1: active writers 1 2024/04/25 05:38:48 DEBUG : Still 1 writers active and 1 cache items in use, waiting 10ms 2024/04/25 05:38:48 DEBUG : : Looking for writers 2024/04/25 05:38:48 DEBUG : file1: reading active writers 2024/04/25 05:38:48 DEBUG : file1: active writers 1 2024/04/25 05:38:48 DEBUG : Still 1 writers active and 1 cache items in use, waiting 20ms 2024/04/25 05:38:48 DEBUG : : Looking for writers 2024/04/25 05:38:48 DEBUG : file1: reading active writers 2024/04/25 05:38:48 DEBUG : file1: active writers 1 2024/04/25 05:38:48 DEBUG : Still 1 writers active and 1 cache items in use, waiting 40ms 2024/04/25 05:38:48 DEBUG : : Looking for writers 2024/04/25 05:38:48 DEBUG : file1: reading active writers 2024/04/25 05:38:48 DEBUG : file1: active writers 1 2024/04/25 05:38:48 DEBUG : Still 1 writers active and 1 cache items in use, waiting 80ms 2024/04/25 05:38:48 DEBUG : : Looking for writers 2024/04/25 05:38:48 DEBUG : file1: reading active writers 2024/04/25 05:38:48 DEBUG : file1: active writers 1 2024/04/25 05:38:48 DEBUG : Still 1 writers active and 1 cache items in use, waiting 160ms 2024/04/25 05:38:49 DEBUG : : Looking for writers 2024/04/25 05:38:49 DEBUG : file1: reading active writers 2024/04/25 05:38:49 DEBUG : file1: active writers 1 2024/04/25 05:38:49 DEBUG : Still 1 writers active and 1 cache items in use, waiting 320ms 2024/04/25 05:38:49 DEBUG : : Looking for writers 2024/04/25 05:38:49 DEBUG : file1: reading active writers 2024/04/25 05:38:49 DEBUG : file1: active writers 1 2024/04/25 05:38:49 DEBUG : Still 1 writers active and 1 cache items in use, waiting 640ms 2024/04/25 05:38:50 DEBUG : : Looking for writers 2024/04/25 05:38:50 DEBUG : file1: reading active writers 2024/04/25 05:38:50 DEBUG : file1: active writers 1 2024/04/25 05:38:50 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:51 DEBUG : : Looking for writers 2024/04/25 05:38:51 DEBUG : file1: reading active writers 2024/04/25 05:38:51 DEBUG : file1: active writers 1 2024/04/25 05:38:51 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:52 DEBUG : : Looking for writers 2024/04/25 05:38:52 DEBUG : file1: reading active writers 2024/04/25 05:38:52 DEBUG : file1: active writers 1 2024/04/25 05:38:52 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:53 DEBUG : : Looking for writers 2024/04/25 05:38:53 DEBUG : file1: reading active writers 2024/04/25 05:38:53 DEBUG : file1: active writers 1 2024/04/25 05:38:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:54 DEBUG : : Looking for writers 2024/04/25 05:38:54 DEBUG : file1: reading active writers 2024/04/25 05:38:54 DEBUG : file1: active writers 1 2024/04/25 05:38:54 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:55 DEBUG : : Looking for writers 2024/04/25 05:38:55 DEBUG : file1: reading active writers 2024/04/25 05:38:55 DEBUG : file1: active writers 1 2024/04/25 05:38:55 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:56 DEBUG : : Looking for writers 2024/04/25 05:38:56 DEBUG : file1: reading active writers 2024/04/25 05:38:56 DEBUG : file1: active writers 1 2024/04/25 05:38:56 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:57 DEBUG : : Looking for writers 2024/04/25 05:38:57 DEBUG : file1: reading active writers 2024/04/25 05:38:57 DEBUG : file1: active writers 1 2024/04/25 05:38:57 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:58 DEBUG : : Looking for writers 2024/04/25 05:38:58 DEBUG : file1: reading active writers 2024/04/25 05:38:58 DEBUG : file1: active writers 1 2024/04/25 05:38:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:38:59 DEBUG : : Looking for writers 2024/04/25 05:38:59 DEBUG : file1: reading active writers 2024/04/25 05:38:59 DEBUG : file1: active writers 1 2024/04/25 05:38:59 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:00 DEBUG : : Looking for writers 2024/04/25 05:39:00 DEBUG : file1: reading active writers 2024/04/25 05:39:00 DEBUG : file1: active writers 1 2024/04/25 05:39:00 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:01 DEBUG : : Looking for writers 2024/04/25 05:39:01 DEBUG : file1: reading active writers 2024/04/25 05:39:01 DEBUG : file1: active writers 1 2024/04/25 05:39:01 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:02 DEBUG : : Looking for writers 2024/04/25 05:39:02 DEBUG : file1: reading active writers 2024/04/25 05:39:02 DEBUG : file1: active writers 1 2024/04/25 05:39:02 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:03 DEBUG : : Looking for writers 2024/04/25 05:39:03 DEBUG : file1: reading active writers 2024/04/25 05:39:03 DEBUG : file1: active writers 1 2024/04/25 05:39:03 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:04 DEBUG : : Looking for writers 2024/04/25 05:39:04 DEBUG : file1: reading active writers 2024/04/25 05:39:04 DEBUG : file1: active writers 1 2024/04/25 05:39:04 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:05 DEBUG : : Looking for writers 2024/04/25 05:39:05 DEBUG : file1: reading active writers 2024/04/25 05:39:05 DEBUG : file1: active writers 1 2024/04/25 05:39:05 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:06 DEBUG : : Looking for writers 2024/04/25 05:39:06 DEBUG : file1: reading active writers 2024/04/25 05:39:06 DEBUG : file1: active writers 1 2024/04/25 05:39:06 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:07 DEBUG : : Looking for writers 2024/04/25 05:39:07 DEBUG : file1: reading active writers 2024/04/25 05:39:07 DEBUG : file1: active writers 1 2024/04/25 05:39:07 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:08 DEBUG : : Looking for writers 2024/04/25 05:39:08 DEBUG : file1: reading active writers 2024/04/25 05:39:08 DEBUG : file1: active writers 1 2024/04/25 05:39:08 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:09 DEBUG : : Looking for writers 2024/04/25 05:39:09 DEBUG : file1: reading active writers 2024/04/25 05:39:09 DEBUG : file1: active writers 1 2024/04/25 05:39:09 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:10 DEBUG : : Looking for writers 2024/04/25 05:39:10 DEBUG : file1: reading active writers 2024/04/25 05:39:10 DEBUG : file1: active writers 1 2024/04/25 05:39:10 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:11 DEBUG : : Looking for writers 2024/04/25 05:39:11 DEBUG : file1: reading active writers 2024/04/25 05:39:11 DEBUG : file1: active writers 1 2024/04/25 05:39:11 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:12 DEBUG : : Looking for writers 2024/04/25 05:39:12 DEBUG : file1: reading active writers 2024/04/25 05:39:12 DEBUG : file1: active writers 1 2024/04/25 05:39:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:13 DEBUG : : Looking for writers 2024/04/25 05:39:13 DEBUG : file1: reading active writers 2024/04/25 05:39:13 DEBUG : file1: active writers 1 2024/04/25 05:39:13 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:14 DEBUG : : Looking for writers 2024/04/25 05:39:14 DEBUG : file1: reading active writers 2024/04/25 05:39:14 DEBUG : file1: active writers 1 2024/04/25 05:39:14 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:15 DEBUG : : Looking for writers 2024/04/25 05:39:15 DEBUG : file1: reading active writers 2024/04/25 05:39:15 DEBUG : file1: active writers 1 2024/04/25 05:39:15 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:16 DEBUG : : Looking for writers 2024/04/25 05:39:16 DEBUG : file1: reading active writers 2024/04/25 05:39:16 DEBUG : file1: active writers 1 2024/04/25 05:39:16 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:17 DEBUG : : Looking for writers 2024/04/25 05:39:17 DEBUG : file1: reading active writers 2024/04/25 05:39:17 DEBUG : file1: active writers 1 2024/04/25 05:39:17 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:18 DEBUG : : Looking for writers 2024/04/25 05:39:18 DEBUG : file1: reading active writers 2024/04/25 05:39:18 DEBUG : file1: active writers 1 2024/04/25 05:39:18 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s 2024/04/25 05:39:18 ERROR : Exiting even though 1 writers active and 1 cache items in use after 30s Cache{ "file1": &{c:0xc001c0e0f0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000d8ea08 notify:{wait:0 notify:0 lock:0 head: tail:} checker:824647936576} name:file1 opens:1 downloaders: o: fd:0xc000798168 info:{ModTime:{wall:13943875596028508200 ext:430859515550 loc:0x3a135e0} ATime:{wall:13943875596028508200 ext:430859515550 loc:0x3a135e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:0 pendingAccesses:0 modified:true beingReset:false}, } 2024/04/25 05:39:18 DEBUG : >WaitForWriters: 2024/04/25 05:39:18 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWFileModTimeWithOpenWriters (31.50s) === RUN TestRWCacheUpdate run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:39:19 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:39:19 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone" 2024/04/25 05:39:19 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:39:19 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:39:19 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfs/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:39:19 DEBUG : Creating backend with remote "/home/rclone/.cache/rclone/vfsMeta/TestProtonDrive/rclone-test-juyozol5tupefig5didovoc7" 2024/04/25 05:39:19 INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2024/04/25 05:39:19 DEBUG : : forgetting directory cache 2024/04/25 05:39:19.993894 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:39:19.993934 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:39:19 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:39:19 DEBUG : : Looking for writers 2024/04/25 05:39:19 DEBUG : >WaitForWriters: 2024/04/25 05:39:19 DEBUG : vfs cache: cleaner exiting --- FAIL: TestRWCacheUpdate (1.18s) === RUN TestCaseSensitivity run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:39:21.127507 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:39:21.127545 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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.12s) === RUN TestUnicodeNormalization run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:39:22.300870 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:39:22.300909 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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.22s) === RUN TestVFSStat run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:39:22 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:39:22 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:39:23.643612 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:39:23.643641 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-juyozol5tupefig5didovoc7'": 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:39:24 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:39:24 DEBUG : : Looking for writers 2024/04/25 05:39:24 DEBUG : >WaitForWriters: --- FAIL: TestVFSStat (2.40s) === RUN TestVFSStatParent run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:39:25 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:39:25 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:39:26.109098 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:39:26.109126 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-juyozol5tupefig5didovoc7'": 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:39:27 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:39:27 DEBUG : : Looking for writers 2024/04/25 05:39:27 DEBUG : >WaitForWriters: --- FAIL: TestVFSStatParent (2.53s) === RUN TestVFSOpenFile run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:39:27 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:39:27 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:39:28.537487 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:39:28.537514 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-juyozol5tupefig5didovoc7'": 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:39:29 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:39:29 DEBUG : : Looking for writers 2024/04/25 05:39:29 DEBUG : >WaitForWriters: --- FAIL: TestVFSOpenFile (2.26s) === RUN TestVFSRename run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:39:30 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:39:30 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:39:31.496863 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:39:31.496889 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-juyozol5tupefig5didovoc7'": 400 POST https://mail.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:39:31 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:39:31 DEBUG : : Looking for writers 2024/04/25 05:39:31 DEBUG : >WaitForWriters: --- FAIL: TestVFSRename (2.75s) === RUN TestWriteFileHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:39:32 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:39:32 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:39:32 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:39:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:39:33 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:39:33 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:39:33 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:39:33 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:39:33 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2024/04/25 05:39:33 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2024/04/25 05:39:33 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2024/04/25 05:39:33 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': File to upload is small (5 bytes), uploading instead of streaming 2024/04/25 05:39:33.924630 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:39:33.924660 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:39: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:39:34 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:39:34 DEBUG : file1: Remove: 2024/04/25 05:39:34 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:39:34 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:40:10 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:40:10 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:40:10 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:40:10 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:40:10 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:40:10 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:40:10 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:40:25.871539 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:40:25.871575 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:40:26 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:40:26 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:40:26 DEBUG : file1: Remove: 2024/04/25 05:40:26 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:40:26 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:40:26 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:40:26 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:40:26 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:40:26 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:40:26 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:40:26 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:40:26 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:40:26 DEBUG : : Looking for writers 2024/04/25 05:40:26 DEBUG : file1: reading active writers 2024/04/25 05:40:26 DEBUG : file1: active writers 1 2024/04/25 05:40:26 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms 2024/04/25 05:40:26 DEBUG : : Looking for writers 2024/04/25 05:40:26 DEBUG : file1: reading active writers 2024/04/25 05:40:26 DEBUG : file1: active writers 1 2024/04/25 05:40:26 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms 2024/04/25 05:40:27 DEBUG : : Looking for writers 2024/04/25 05:40:27 DEBUG : file1: reading active writers 2024/04/25 05:40:27 DEBUG : file1: active writers 1 2024/04/25 05:40:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms 2024/04/25 05:40:27 DEBUG : : Looking for writers 2024/04/25 05:40:27 DEBUG : file1: reading active writers 2024/04/25 05:40:27 DEBUG : file1: active writers 1 2024/04/25 05:40:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms 2024/04/25 05:40:27 DEBUG : : Looking for writers 2024/04/25 05:40:27 DEBUG : file1: reading active writers 2024/04/25 05:40:27 DEBUG : file1: active writers 1 2024/04/25 05:40:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms 2024/04/25 05:40:27 DEBUG : : Looking for writers 2024/04/25 05:40:27 DEBUG : file1: reading active writers 2024/04/25 05:40:27 DEBUG : file1: active writers 1 2024/04/25 05:40:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms 2024/04/25 05:40:27 DEBUG : : Looking for writers 2024/04/25 05:40:27 DEBUG : file1: reading active writers 2024/04/25 05:40:27 DEBUG : file1: active writers 1 2024/04/25 05:40:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms 2024/04/25 05:40:28 DEBUG : : Looking for writers 2024/04/25 05:40:28 DEBUG : file1: reading active writers 2024/04/25 05:40:28 DEBUG : file1: active writers 1 2024/04/25 05:40:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:29 DEBUG : : Looking for writers 2024/04/25 05:40:29 DEBUG : file1: reading active writers 2024/04/25 05:40:29 DEBUG : file1: active writers 1 2024/04/25 05:40:29 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:30 DEBUG : : Looking for writers 2024/04/25 05:40:30 DEBUG : file1: reading active writers 2024/04/25 05:40:30 DEBUG : file1: active writers 1 2024/04/25 05:40:30 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:31 DEBUG : : Looking for writers 2024/04/25 05:40:31 DEBUG : file1: reading active writers 2024/04/25 05:40:31 DEBUG : file1: active writers 1 2024/04/25 05:40:31 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:32 DEBUG : : Looking for writers 2024/04/25 05:40:32 DEBUG : file1: reading active writers 2024/04/25 05:40:32 DEBUG : file1: active writers 1 2024/04/25 05:40:32 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:33 DEBUG : : Looking for writers 2024/04/25 05:40:33 DEBUG : file1: reading active writers 2024/04/25 05:40:33 DEBUG : file1: active writers 1 2024/04/25 05:40:33 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:34 DEBUG : : Looking for writers 2024/04/25 05:40:34 DEBUG : file1: reading active writers 2024/04/25 05:40:34 DEBUG : file1: active writers 1 2024/04/25 05:40:34 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:35 DEBUG : : Looking for writers 2024/04/25 05:40:35 DEBUG : file1: reading active writers 2024/04/25 05:40:35 DEBUG : file1: active writers 1 2024/04/25 05:40:35 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:36 DEBUG : : Looking for writers 2024/04/25 05:40:36 DEBUG : file1: reading active writers 2024/04/25 05:40:36 DEBUG : file1: active writers 1 2024/04/25 05:40:36 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:37 DEBUG : : Looking for writers 2024/04/25 05:40:37 DEBUG : file1: reading active writers 2024/04/25 05:40:37 DEBUG : file1: active writers 1 2024/04/25 05:40:37 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:38 DEBUG : : Looking for writers 2024/04/25 05:40:38 DEBUG : file1: reading active writers 2024/04/25 05:40:38 DEBUG : file1: active writers 1 2024/04/25 05:40:38 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:39 DEBUG : : Looking for writers 2024/04/25 05:40:39 DEBUG : file1: reading active writers 2024/04/25 05:40:39 DEBUG : file1: active writers 1 2024/04/25 05:40:39 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:40 DEBUG : : Looking for writers 2024/04/25 05:40:40 DEBUG : file1: reading active writers 2024/04/25 05:40:40 DEBUG : file1: active writers 1 2024/04/25 05:40:40 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:41 DEBUG : : Looking for writers 2024/04/25 05:40:41 DEBUG : file1: reading active writers 2024/04/25 05:40:41 DEBUG : file1: active writers 1 2024/04/25 05:40:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:42 DEBUG : : Looking for writers 2024/04/25 05:40:42 DEBUG : file1: reading active writers 2024/04/25 05:40:42 DEBUG : file1: active writers 1 2024/04/25 05:40:42 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:43 DEBUG : : Looking for writers 2024/04/25 05:40:43 DEBUG : file1: reading active writers 2024/04/25 05:40:43 DEBUG : file1: active writers 1 2024/04/25 05:40:43 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:44 DEBUG : : Looking for writers 2024/04/25 05:40:44 DEBUG : file1: reading active writers 2024/04/25 05:40:44 DEBUG : file1: active writers 1 2024/04/25 05:40:44 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:45 DEBUG : : Looking for writers 2024/04/25 05:40:45 DEBUG : file1: reading active writers 2024/04/25 05:40:45 DEBUG : file1: active writers 1 2024/04/25 05:40:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:46 DEBUG : : Looking for writers 2024/04/25 05:40:46 DEBUG : file1: reading active writers 2024/04/25 05:40:46 DEBUG : file1: active writers 1 2024/04/25 05:40:46 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:47 DEBUG : : Looking for writers 2024/04/25 05:40:47 DEBUG : file1: reading active writers 2024/04/25 05:40:47 DEBUG : file1: active writers 1 2024/04/25 05:40:47 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:48 DEBUG : : Looking for writers 2024/04/25 05:40:48 DEBUG : file1: reading active writers 2024/04/25 05:40:48 DEBUG : file1: active writers 1 2024/04/25 05:40:48 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:49 DEBUG : : Looking for writers 2024/04/25 05:40:49 DEBUG : file1: reading active writers 2024/04/25 05:40:49 DEBUG : file1: active writers 1 2024/04/25 05:40:49 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:50 DEBUG : : Looking for writers 2024/04/25 05:40:50 DEBUG : file1: reading active writers 2024/04/25 05:40:50 DEBUG : file1: active writers 1 2024/04/25 05:40:50 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:51 DEBUG : : Looking for writers 2024/04/25 05:40:51 DEBUG : file1: reading active writers 2024/04/25 05:40:51 DEBUG : file1: active writers 1 2024/04/25 05:40:51 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:52 DEBUG : : Looking for writers 2024/04/25 05:40:52 DEBUG : file1: reading active writers 2024/04/25 05:40:52 DEBUG : file1: active writers 1 2024/04/25 05:40:52 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:53 DEBUG : : Looking for writers 2024/04/25 05:40:53 DEBUG : file1: reading active writers 2024/04/25 05:40:53 DEBUG : file1: active writers 1 2024/04/25 05:40:53 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:54 DEBUG : : Looking for writers 2024/04/25 05:40:54 DEBUG : file1: reading active writers 2024/04/25 05:40:54 DEBUG : file1: active writers 1 2024/04/25 05:40:54 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:55 DEBUG : : Looking for writers 2024/04/25 05:40:55 DEBUG : file1: reading active writers 2024/04/25 05:40:55 DEBUG : file1: active writers 1 2024/04/25 05:40:55 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:56 DEBUG : : Looking for writers 2024/04/25 05:40:56 DEBUG : file1: reading active writers 2024/04/25 05:40:56 DEBUG : file1: active writers 1 2024/04/25 05:40:56 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2024/04/25 05:40:56 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s Cache: 2024/04/25 05:40:56 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleMethods (84.72s) === RUN TestWriteFileHandleWriteAt run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:40:57 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:40:57 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:40:57 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:40:57 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:40:57 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:40:57 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:40:57 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:40:57 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:40:57 DEBUG : file1: waiting for in-sequence write to 100 for 1s 2024/04/25 05:40:58 DEBUG : file1: aborting in-sequence write wait, off=100 2024/04/25 05:40:58 DEBUG : file1: failed to wait for in-sequence write to 100 2024/04/25 05:40:58 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes 2024/04/25 05:40:58 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': File to upload is small (11 bytes), uploading instead of streaming 2024/04/25 05:40:59.627334 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:40:59.627375 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:41:00 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:41:00 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:41:00 DEBUG : file1: Remove: 2024/04/25 05:41:00 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:41:00 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:41:00 ERROR : file1: WriteFileHandle.Write: error: Bad file descriptor dir_test.go:247: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:247 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:231 Error: Not equal: expected: []string{"file1,11,false"} actual : []string(nil) Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=1) { - (string) (len=14) "file1,11,false" -} +([]string) Test: TestWriteFileHandleWriteAt fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:235 Error: Should be true Test: TestWriteFileHandleWriteAt Messages: listing wrong, want file1 (11) got fstest.go:197: Not found "file1" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:235 Error: Not equal: expected: 0 actual : 1 Test: TestWriteFileHandleWriteAt Messages: 1 objects not found 2024/04/25 05:41:36 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:41:36 DEBUG : : Looking for writers 2024/04/25 05:41:36 DEBUG : >WaitForWriters: 2024/04/25 05:41:38 DEBUG : : forgetting directory cache 2024/04/25 05:41:42 DEBUG : : forgetting directory cache 2024/04/25 05:41:44 DEBUG : : forgetting directory cache 2024/04/25 05:41:47 DEBUG : : forgetting directory cache 2024/04/25 05:41:50 DEBUG : : forgetting directory cache --- FAIL: TestWriteFileHandleWriteAt (52.94s) === RUN TestWriteFileHandleFlush run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:41:50 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:41:50 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:41:50 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:41:50 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:41:50 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:41:50 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:41:50 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:41:50 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2024/04/25 05:41:50 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:41:50 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': File to upload is small (5 bytes), uploading instead of streaming 2024/04/25 05:41:51.508401 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:41:51.508453 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:41: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:41:52 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:41:52 DEBUG : file1: Remove: 2024/04/25 05:41:52 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:41:52 DEBUG : file1: >Remove: err= 2024/04/25 05:41:52 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:41:52 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:41:52 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:41:52 DEBUG : : Looking for writers 2024/04/25 05:41:52 DEBUG : >WaitForWriters: 2024/04/25 05:41:52 DEBUG : : forgetting directory cache --- FAIL: TestWriteFileHandleFlush (2.61s) === RUN TestWriteFileHandleRelease run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:41:53 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:41:53 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:41:53 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:41:53 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:41:53 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:41:53 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:41:53 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:41:53 DEBUG : file1: WriteFileHandle.Release closing 2024/04/25 05:41:53 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:41:53 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:41:54.133843 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:41:54.133876 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:41:55 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:41:55 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:41:55 DEBUG : file1: Remove: 2024/04/25 05:41:55 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:41:55 DEBUG : file1: >Remove: err= 2024/04/25 05:41:55 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:41:55 DEBUG : file1: WriteFileHandle.Release nothing to do 2024/04/25 05:41:55 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:41:55 DEBUG : : Looking for writers 2024/04/25 05:41:55 DEBUG : >WaitForWriters: 2024/04/25 05:41:55 DEBUG : : forgetting directory cache --- FAIL: TestWriteFileHandleRelease (2.58s) === RUN TestWriteFileModTimeWithOpenWriters run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:41:55 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:41:55 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:41:55 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:41:56 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:41:56 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:41:56 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:41:56 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:41:56 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:41:56 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': File to upload is small (2 bytes), uploading instead of streaming 2024/04/25 05:41:56.673425 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:41:56.673452 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:41:57 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:41:57 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:41:57 DEBUG : file1: Remove: 2024/04/25 05:41:57 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:41:57 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:41:57 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:41:57 DEBUG : : Looking for writers 2024/04/25 05:41:57 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileModTimeWithOpenWriters (2.51s) === RUN TestFileReadAtZeroLength run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:41:58 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:41:58 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:41:58 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:41:58 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:41:58 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:41:58 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:41:58 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:41:58 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:41:58 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': File to upload is small (0 bytes), uploading instead of streaming 2024/04/25 05:41:58 DEBUG : : forgetting directory cache 2024/04/25 05:41:59.257412 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:41:59.257441 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:42:00 ERROR : file1: Failed to copy: a draft exist - usually this means a file is being uploaded at another client, or, there was a failed upload attempt. Can use --protondrive-replace-existing-draft=true to temporarily override the existing draft 2024/04/25 05:42:00 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:42:00 DEBUG : file1: Remove: 2024/04/25 05:42:00 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:42:00 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:42:00 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2024/04/25 05:42:00 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:42:00 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:42:00 DEBUG : : Looking for writers 2024/04/25 05:42:00 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtZeroLength (2.57s) === RUN TestFileReadAtNonZeroLength run.go:180: Remote "proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7'", Local "Local file system at /tmp/rclone4022115362", Modify Window "1s" 2024/04/25 05:42:00 INFO : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': poll-interval is not supported by this remote 2024/04/25 05:42:00 NOTICE: proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/25 05:42:00 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2024/04/25 05:42:01 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2024/04/25 05:42:01 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:42:01 DEBUG : file1: >Open: fd=file1 (w), err= 2024/04/25 05:42:01 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2024/04/25 05:42:01 DEBUG : : Added virtual directory entry vAddFile: "file1" 2024/04/25 05:42:01 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': File to upload is small (100 bytes), uploading instead of streaming 2024/04/25 05:42:01 DEBUG : : forgetting directory cache 2024/04/25 05:42:01.813124 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:42:01.813156 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:42: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:42: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:42:02 DEBUG : file1: Remove: 2024/04/25 05:42:02 DEBUG : : Added virtual directory entry vDel: "file1" 2024/04/25 05:42:02 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:42:02 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2024/04/25 05:42:02 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:42:02 DEBUG : WaitForWriters: timeout=30s 2024/04/25 05:42:02 DEBUG : : Looking for writers 2024/04/25 05:42:02 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtNonZeroLength (2.72s) FAIL 2024/04/25 05:42:03 DEBUG : proton drive root link ID 'rclone-test-juyozol5tupefig5didovoc7': 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 10m26.706257037s (try 5/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]