"./vfs.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose" - Starting (try 1/5) 2024/12/15 04:58:00 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-kiqijiz7wolu" 2024/12/15 04:58:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:58:00 DEBUG : proton drive root link ID 'rclone-test-kiqijiz7wolu': Has cached credentials 2024/12/15 04:58:00.553051 WARN RESTY 401 GET https://mail.proton.me/api/core/v4/users: Invalid access token (Code=401, Status=401), Attempt 1 2024/12/15 04:58:00.553076 ERROR RESTY 401 GET https://mail.proton.me/api/core/v4/users: Invalid access token (Code=401, Status=401) 2024/12/15 04:58:00 DEBUG : Saving config "client_uid" in section "TestProtonDrive" of the config file 2024/12/15 04:58:00 DEBUG : Saving config "client_access_token" in section "TestProtonDrive" of the config file 2024/12/15 04:58:00 DEBUG : Saving config "client_refresh_token" in section "TestProtonDrive" of the config file 2024/12/15 04:58:00 DEBUG : Saving config "client_salted_key_pass" in section "TestProtonDrive" of the config file 2024/12/15 04:58:01 DEBUG : proton drive root link ID 'rclone-test-kiqijiz7wolu': Used cached credential to initialize the ProtonDrive API 2024/12/15 04:58:02 DEBUG : Creating backend with remote "/tmp/rclone3921350345" === RUN TestDirHandleMethods run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:02 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:02 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:05 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:05 DEBUG : dir: Looking for writers 2024/12/15 04:58:05 DEBUG : : Looking for writers 2024/12/15 04:58:05 DEBUG : dir: reading active writers 2024/12/15 04:58:05 DEBUG : >WaitForWriters: --- PASS: TestDirHandleMethods (5.35s) === RUN TestDirHandleReaddir run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:07 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:07 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:15 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:15 DEBUG : dir/subdir: Looking for writers 2024/12/15 04:58:15 DEBUG : dir: Looking for writers 2024/12/15 04:58:15 DEBUG : file1: reading active writers 2024/12/15 04:58:15 DEBUG : file2: reading active writers 2024/12/15 04:58:15 DEBUG : subdir: reading active writers 2024/12/15 04:58:15 DEBUG : : Looking for writers 2024/12/15 04:58:15 DEBUG : dir: reading active writers 2024/12/15 04:58:15 DEBUG : >WaitForWriters: --- PASS: TestDirHandleReaddir (10.53s) === RUN TestDirHandleReaddirnames run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:18 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:18 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:20 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:20 DEBUG : dir: Looking for writers 2024/12/15 04:58:20 DEBUG : file1: reading active writers 2024/12/15 04:58:20 DEBUG : : Looking for writers 2024/12/15 04:58:20 DEBUG : dir: reading active writers 2024/12/15 04:58:20 DEBUG : >WaitForWriters: --- PASS: TestDirHandleReaddirnames (4.34s) === RUN TestDirMethods run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:22 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:22 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:24 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:24 DEBUG : dir: Looking for writers 2024/12/15 04:58:24 DEBUG : : Looking for writers 2024/12/15 04:58:24 DEBUG : dir: reading active writers 2024/12/15 04:58:24 DEBUG : >WaitForWriters: --- PASS: TestDirMethods (3.89s) === RUN TestDirForgetAll run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:26 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:26 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:28 DEBUG : dir: forgetting directory cache 2024/12/15 04:58:28 DEBUG : : forgetting directory cache 2024/12/15 04:58:28 DEBUG : dir: forgetting directory cache 2024/12/15 04:58:28 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:28 DEBUG : : Looking for writers 2024/12/15 04:58:28 DEBUG : >WaitForWriters: --- PASS: TestDirForgetAll (4.25s) === RUN TestDirForgetPath run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:30 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:30 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:33 DEBUG : : ForgetPath: relativePath="dir/notfound", type=1 2024/12/15 04:58:33 DEBUG : dir: invalidating directory cache 2024/12/15 04:58:33 DEBUG : : >ForgetPath: 2024/12/15 04:58:33 DEBUG : : ForgetPath: relativePath="dir", type=0 2024/12/15 04:58:33 DEBUG : : invalidating directory cache 2024/12/15 04:58:33 DEBUG : dir: forgetting directory cache 2024/12/15 04:58:33 DEBUG : : >ForgetPath: 2024/12/15 04:58:33 DEBUG : : ForgetPath: relativePath="not/in/cache", type=0 2024/12/15 04:58:33 DEBUG : : >ForgetPath: 2024/12/15 04:58:33 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:33 DEBUG : dir: Looking for writers 2024/12/15 04:58:33 DEBUG : : Looking for writers 2024/12/15 04:58:33 DEBUG : dir: reading active writers 2024/12/15 04:58:33 DEBUG : >WaitForWriters: --- PASS: TestDirForgetPath (4.92s) === RUN TestDirWalk run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:35 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:35 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:41 DEBUG : : forgetting directory cache 2024/12/15 04:58:41 DEBUG : dir: forgetting directory cache 2024/12/15 04:58:42 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:42 DEBUG : dir: Looking for writers 2024/12/15 04:58:42 DEBUG : fil/a/b: Looking for writers 2024/12/15 04:58:42 DEBUG : fil/a: Looking for writers 2024/12/15 04:58:42 DEBUG : b: reading active writers 2024/12/15 04:58:42 DEBUG : fil: Looking for writers 2024/12/15 04:58:42 DEBUG : a: reading active writers 2024/12/15 04:58:42 DEBUG : : Looking for writers 2024/12/15 04:58:42 DEBUG : dir: reading active writers 2024/12/15 04:58:42 DEBUG : fil: reading active writers 2024/12/15 04:58:42 DEBUG : >WaitForWriters: --- PASS: TestDirWalk (10.04s) === RUN TestDirSetModTime run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:45 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:45 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:47 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:47 DEBUG : dir: Looking for writers 2024/12/15 04:58:47 DEBUG : : Looking for writers 2024/12/15 04:58:47 DEBUG : dir: reading active writers 2024/12/15 04:58:47 DEBUG : >WaitForWriters: --- PASS: TestDirSetModTime (4.11s) === RUN TestDirStat run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:49 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:49 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:58:52 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:58:52 DEBUG : dir: Looking for writers 2024/12/15 04:58:52 DEBUG : file1: reading active writers 2024/12/15 04:58:52 DEBUG : : Looking for writers 2024/12/15 04:58:52 DEBUG : dir: reading active writers 2024/12/15 04:58:52 DEBUG : >WaitForWriters: --- PASS: TestDirStat (4.19s) === RUN TestDirReadDirAll run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:58:53 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:58:53 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream === RUN TestDirReadDirAll/Virtual 2024/12/15 04:59:00 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2024/12/15 04:59:00 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2024/12/15 04:59:00 DEBUG : dir: Added virtual directory entry vDel: "file2" 2024/12/15 04:59:00 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2024/12/15 04:59:04 DEBUG : dir: invalidating directory cache 2024/12/15 04:59:04 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2024/12/15 04:59:04 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2024/12/15 04:59:04 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2024/12/15 04:59:04 DEBUG : dir/file1: Reset virtual modtime 2024/12/15 04:59:04 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2024/12/15 04:59:04 DEBUG : dir/virtualFile: Reset virtual modtime 2024/12/15 04:59:04 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2024/12/15 04:59:04 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2024/12/15 04:59:04 DEBUG : dir: Added virtual directory entry vDel: "file1" 2024/12/15 04:59:04 DEBUG : dir: invalidating directory cache 2024/12/15 04:59:05 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2" 2024/12/15 04:59:05 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2024/12/15 04:59:05 DEBUG : dir/virtualFile: Reset virtual modtime 2024/12/15 04:59:05 DEBUG : : forgetting directory cache 2024/12/15 04:59:05 DEBUG : dir: forgetting directory cache 2024/12/15 04:59:05 DEBUG : dir/virtualDir: forgetting directory cache 2024/12/15 04:59:05 DEBUG : dir/file1: Reset virtual modtime 2024/12/15 04:59:05 DEBUG : dir/virtualFile: Reset virtual modtime 2024/12/15 04:59:05 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:59:05 DEBUG : dir/virtualDir: Looking for writers 2024/12/15 04:59:05 DEBUG : dir: Looking for writers 2024/12/15 04:59:05 DEBUG : virtualFile2: reading active writers 2024/12/15 04:59:05 DEBUG : virtualFile: reading active writers 2024/12/15 04:59:05 DEBUG : virtualDir: reading active writers 2024/12/15 04:59:05 DEBUG : file1: reading active writers 2024/12/15 04:59:05 DEBUG : : Looking for writers 2024/12/15 04:59:05 DEBUG : dir: reading active writers 2024/12/15 04:59:05 DEBUG : >WaitForWriters: --- PASS: TestDirReadDirAll (15.17s) --- PASS: TestDirReadDirAll/Virtual (5.72s) === RUN TestDirOpen run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:59:09 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:59:09 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:59:12 ERROR : dir/: Can only open directories read only 2024/12/15 04:59:12 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:59:12 DEBUG : dir: Looking for writers 2024/12/15 04:59:12 DEBUG : : Looking for writers 2024/12/15 04:59:12 DEBUG : dir: reading active writers 2024/12/15 04:59:12 DEBUG : >WaitForWriters: --- PASS: TestDirOpen (4.92s) === RUN TestDirCreate run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:59:13 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:59:13 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 04:59:16 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2024/12/15 04:59:16 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2024/12/15 04:59:16 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2024/12/15 04:59:16 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2024/12/15 04:59:16 DEBUG : proton drive root link ID 'rclone-test-kiqijiz7wolu': File to upload is small (5 bytes), uploading instead of streaming 2024/12/15 04:59:17 DEBUG : dir/potato: sha1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2024/12/15 04:59:17 DEBUG : dir/potato: Size and sha1 of src and dst objects identical 2024/12/15 04:59:17 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2024/12/15 04:59:17 DEBUG : WaitForWriters: timeout=30s 2024/12/15 04:59:17 DEBUG : dir: Looking for writers 2024/12/15 04:59:17 DEBUG : file1: reading active writers 2024/12/15 04:59:17 DEBUG : potato: reading active writers 2024/12/15 04:59:17 DEBUG : : Looking for writers 2024/12/15 04:59:17 DEBUG : dir: reading active writers 2024/12/15 04:59:17 DEBUG : >WaitForWriters: fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- PASS: TestDirCreate (42.94s) === RUN TestDirMkdir run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 04:59:56 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 04:59:56 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 05:00:00 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2024/12/15 05:00:02 DEBUG : WaitForWriters: timeout=30s 2024/12/15 05:00:02 DEBUG : dir/sub: Looking for writers 2024/12/15 05:00:02 DEBUG : dir: Looking for writers 2024/12/15 05:00:02 DEBUG : sub: reading active writers 2024/12/15 05:00:02 DEBUG : file1: reading active writers 2024/12/15 05:00:02 DEBUG : : Looking for writers 2024/12/15 05:00:02 DEBUG : dir: reading active writers 2024/12/15 05:00:02 DEBUG : >WaitForWriters: --- PASS: TestDirMkdir (7.60s) === RUN TestDirMkdirSub run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 05:00:04 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 05:00:04 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 05:00:07 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2024/12/15 05:00:08 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub" 2024/12/15 05:00:09 DEBUG : WaitForWriters: timeout=30s 2024/12/15 05:00:09 DEBUG : dir/sub/subsub: Looking for writers 2024/12/15 05:00:09 DEBUG : dir/sub: Looking for writers 2024/12/15 05:00:09 DEBUG : subsub: reading active writers 2024/12/15 05:00:09 DEBUG : dir: Looking for writers 2024/12/15 05:00:09 DEBUG : file1: reading active writers 2024/12/15 05:00:09 DEBUG : sub: reading active writers 2024/12/15 05:00:09 DEBUG : : Looking for writers 2024/12/15 05:00:09 DEBUG : dir: reading active writers 2024/12/15 05:00:09 DEBUG : >WaitForWriters: --- PASS: TestDirMkdirSub (8.12s) === RUN TestDirRemove run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 05:00:12 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 05:00:12 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 05:00:15 ERROR : dir/: Dir.Remove not empty 2024/12/15 05:00:15 DEBUG : dir/file1: Remove: 2024/12/15 05:00:15 DEBUG : dir: Added virtual directory entry vDel: "file1" 2024/12/15 05:00:15 DEBUG : dir/file1: >Remove: err= 2024/12/15 05:00:16 DEBUG : : Added virtual directory entry vDel: "dir" 2024/12/15 05:00:16 DEBUG : WaitForWriters: timeout=30s 2024/12/15 05:00:16 DEBUG : : Looking for writers 2024/12/15 05:00:16 DEBUG : >WaitForWriters: --- PASS: TestDirRemove (4.63s) === RUN TestDirRemoveAll run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 05:00:17 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 05:00:17 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 05:00:20 DEBUG : dir/file1: Remove: 2024/12/15 05:00:20 DEBUG : dir: Added virtual directory entry vDel: "file1" 2024/12/15 05:00:20 DEBUG : dir/file1: >Remove: err= 2024/12/15 05:00:21 DEBUG : : Added virtual directory entry vDel: "dir" 2024/12/15 05:00:21 DEBUG : WaitForWriters: timeout=30s 2024/12/15 05:00:21 DEBUG : : Looking for writers 2024/12/15 05:00:21 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveAll (5.00s) === RUN TestDirRemoveName run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 05:00:22 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 05:00:22 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 05:00:25 DEBUG : dir/file1: Remove: 2024/12/15 05:00:25 DEBUG : dir: Added virtual directory entry vDel: "file1" 2024/12/15 05:00:25 DEBUG : dir/file1: >Remove: err= 2024/12/15 05:00:26 DEBUG : WaitForWriters: timeout=30s 2024/12/15 05:00:26 DEBUG : dir: Looking for writers 2024/12/15 05:00:26 DEBUG : : Looking for writers 2024/12/15 05:00:26 DEBUG : dir: reading active writers 2024/12/15 05:00:26 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveName (5.07s) === RUN TestDirRename run.go:180: Remote "proton drive root link ID 'rclone-test-kiqijiz7wolu'", Local "Local file system at /tmp/rclone3921350345", Modify Window "1s" 2024/12/15 05:00:27 INFO : proton drive root link ID 'rclone-test-kiqijiz7wolu': poll-interval is not supported by this remote 2024/12/15 05:00:27 NOTICE: proton drive root link ID 'rclone-test-kiqijiz7wolu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/12/15 05:00:32 ERROR : dir/not found: Dir.Rename error: file does not exist 2024/12/15 05:00:32.955378 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QJyebwjLjJ8-eAaHO_p_Vms62Xj0erNNTRWHLGZP4-LKqzalJ0kFZU86sQ9rlZjMvWdreeP_80qBarG_heJz0w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2024/12/15 05:00:32.955400 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QJyebwjLjJ8-eAaHO_p_Vms62Xj0erNNTRWHLGZP4-LKqzalJ0kFZU86sQ9rlZjMvWdreeP_80qBarG_heJz0w==/move: This value should not be blank. (Code=2000, Status=400) 2024/12/15 05:00:32 ERROR : dir: Dir.Rename error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QJyebwjLjJ8-eAaHO_p_Vms62Xj0erNNTRWHLGZP4-LKqzalJ0kFZU86sQ9rlZjMvWdreeP_80qBarG_heJz0w==/move: This value should not be blank. (Code=2000, Status=400) dir_test.go:535: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:535 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QJyebwjLjJ8-eAaHO_p_Vms62Xj0erNNTRWHLGZP4-LKqzalJ0kFZU86sQ9rlZjMvWdreeP_80qBarG_heJz0w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestDirRename 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/dir_test.go:536 Error: Not equal: expected: []string{"dir2,0,true"} actual : []string{"dir,0,true"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=11) "dir2,0,true" + (string) (len=10) "dir,0,true" } Test: TestDirRename 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/dir_test.go:542 Error: Should be true Test: TestDirRename Messages: listing wrong, want dir2/file1 (14), dir2/file3 (15) got dir/file1 (14), dir/file3 (15) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:542 Error: Should be true Test: TestDirRename Messages: Unexpected file "dir/file1" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:542 Error: Should be true Test: TestDirRename Messages: Unexpected file "dir/file3" fstest.go:197: Not found "dir2/file1" fstest.go:197: Not found "dir2/file3" 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/dir_test.go:542 Error: Not equal: expected: 0 actual : 2 Test: TestDirRename Messages: 2 objects not found fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:542 Error: Not equal: expected: []string{"dir2"} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=4) "dir2" + (string) (len=3) "dir" } Test: TestDirRename Messages: directories dir_test.go:546: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:546 Error: Received unexpected error: file does not exist Test: TestDirRename 2024/12/15 05:01:41 DEBUG : WaitForWriters: timeout=30s 2024/12/15 05:01:41 DEBUG : dir: Looking for writers 2024/12/15 05:01:41 DEBUG : file1: reading active writers 2024/12/15 05:01:41 DEBUG : file3: reading active writers 2024/12/15 05:01:41 DEBUG : : Looking for writers 2024/12/15 05:01:41 DEBUG : dir: reading active writers 2024/12/15 05:01:41 DEBUG : >WaitForWriters: --- FAIL: TestDirRename (105.23s) panic: interface conversion: vfs.Node is nil, not *vfs.Dir [recovered] panic: interface conversion: vfs.Node is nil, not *vfs.Dir goroutine 1797 [running]: testing.tRunner.func1.2({0x206cf60, 0xc000136d20}) /usr/local/go/src/testing/testing.go:1631 +0x24a testing.tRunner.func1() /usr/local/go/src/testing/testing.go:1634 +0x377 panic({0x206cf60?, 0xc000136d20?}) /usr/local/go/src/runtime/panic.go:770 +0x132 github.com/rclone/rclone/vfs.TestDirRename(0xc00259e000) /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:547 +0x110b testing.tRunner(0xc00259e000, 0x25dbc70) /usr/local/go/src/testing/testing.go:1689 +0xfb created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1742 +0x390 "./vfs.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose" - Finished ERROR in 4m12.153747115s (try 1/5): exit status 2: Failed [TestDirRename]