"./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 3/5) 2025/09/02 02:25:22 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje" 2025/09/02 02:25:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/02 02:25:22 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Has cached credentials 2025/09/02 02:25:24 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:25:24 DEBUG : Creating backend with remote "/tmp/rclone874869990" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:25:29 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fewehid8kufa" 2025/09/02 02:25:29 DEBUG : proton drive root link ID 'rclone-test-fewehid8kufa': Has cached credentials 2025/09/02 02:25:30 DEBUG : proton drive root link ID 'rclone-test-fewehid8kufa': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-lopoduy9keje' -> proton drive root link ID 'rclone-test-fewehid8kufa' 2025/09/02 02:25:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/02 02:25:31 DEBUG : proton drive root link ID 'rclone-test-fewehid8kufa': Waiting for checks to finish 2025/09/02 02:25:31 DEBUG : proton drive root link ID 'rclone-test-fewehid8kufa': Waiting for transfers to finish 2025/09/02 02:25:36 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/02 02:25:36 INFO : sub dir/hello world: Copied (new) 2025/09/02 02:25:38.021232 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 2025/09/02 02:25:38.021309 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) 2025/09/02 02:25:40 DEBUG : proton drive root link ID 'rclone-test-fewehid8kufa': Using server-side directory move 2025/09/02 02:25:40 INFO : proton drive root link ID 'rclone-test-fewehid8kufa': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/02 02:25:41 DEBUG : proton drive root link ID 'rclone-test-fewehid8kufa': Waiting for checks to finish 2025/09/02 02:25:41 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/02 02:25:41 DEBUG : proton drive root link ID 'rclone-test-fewehid8kufa': Waiting for transfers to finish 2025/09/02 02:25:42 INFO : sub dir/hello world: Deleted 2025/09/02 02:25:43.038044 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G_4stSA38nK7eZ4X809lBmaTqK2IpKMr-4eY6eMJeqVCqWN6Fl-UUG9Ra7axyvsm2wNlTAEn86TUuzPQX-Oq9Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:25:43.038078 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G_4stSA38nK7eZ4X809lBmaTqK2IpKMr-4eY6eMJeqVCqWN6Fl-UUG9Ra7axyvsm2wNlTAEn86TUuzPQX-Oq9Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:25:43 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G_4stSA38nK7eZ4X809lBmaTqK2IpKMr-4eY6eMJeqVCqWN6Fl-UUG9Ra7axyvsm2wNlTAEn86TUuzPQX-Oq9Q==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:714: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:714 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G_4stSA38nK7eZ4X809lBmaTqK2IpKMr-4eY6eMJeqVCqWN6Fl-UUG9Ra7axyvsm2wNlTAEn86TUuzPQX-Oq9Q==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/09/02 02:25:43 DEBUG : proton drive root link ID 'rclone-test-fewehid8kufa': Purge remote --- FAIL: TestServerSideMoveOverSelf (21.10s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/09/02 02:25:46 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Making map for --track-renames 2025/09/02 02:25:46 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Finished making map for --track-renames 2025/09/02 02:25:46 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for checks to finish 2025/09/02 02:25:46 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/02 02:25:46 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/02 02:25:46 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for renames to finish 2025/09/02 02:25:46 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for transfers to finish 2025/09/02 02:25:47 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/02 02:25:47 INFO : potato: Copied (new) 2025/09/02 02:25:47 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/02 02:25:47 INFO : yam: Copied (new) 2025/09/02 02:25:47 DEBUG : Waiting for deletions to finish 2025/09/02 02:25:49 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/02 02:25:49 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Making map for --track-renames 2025/09/02 02:25:49 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Finished making map for --track-renames 2025/09/02 02:25:49 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for checks to finish 2025/09/02 02:25:49 DEBUG : potato: Unchanged skipping 2025/09/02 02:25:49 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for renames to finish 2025/09/02 02:25:50.128314 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gdJHt-gs8TSQNKBLKUbSED52XDUCmEEO1PWARHTnDfbrS024FYr6-9SJci-ebGj07gdkW5RqWFWclz2ugh7JmQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:25:50.128362 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gdJHt-gs8TSQNKBLKUbSED52XDUCmEEO1PWARHTnDfbrS024FYr6-9SJci-ebGj07gdkW5RqWFWclz2ugh7JmQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:25:50 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gdJHt-gs8TSQNKBLKUbSED52XDUCmEEO1PWARHTnDfbrS024FYr6-9SJci-ebGj07gdkW5RqWFWclz2ugh7JmQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:25:50 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gdJHt-gs8TSQNKBLKUbSED52XDUCmEEO1PWARHTnDfbrS024FYr6-9SJci-ebGj07gdkW5RqWFWclz2ugh7JmQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:25:50 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/02 02:25:50 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for transfers to finish 2025/09/02 02:25:51 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/02 02:25:51 INFO : yaml: Copied (new) 2025/09/02 02:25:51 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje': not deleting files as there were IO errors 2025/09/02 02:25:51 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje': not deleting directories as there were IO errors sync_test.go:1586: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1586 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (8.51s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/09/02 02:25:54 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Making map for --track-renames 2025/09/02 02:25:54 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Finished making map for --track-renames 2025/09/02 02:25:54 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for checks to finish 2025/09/02 02:25:54 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/02 02:25:54 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/02 02:25:54 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for renames to finish 2025/09/02 02:25:54 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for transfers to finish 2025/09/02 02:25:56 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/02 02:25:56 INFO : potato: Copied (new) 2025/09/02 02:25:56 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/02 02:25:56 INFO : yam: Copied (new) 2025/09/02 02:25:56 DEBUG : Waiting for deletions to finish 2025/09/02 02:25:57 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/02 02:25:57 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Making map for --track-renames 2025/09/02 02:25:57 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Finished making map for --track-renames 2025/09/02 02:25:57 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for checks to finish 2025/09/02 02:25:57 DEBUG : potato: Unchanged skipping 2025/09/02 02:25:57 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for renames to finish 2025/09/02 02:25:58.582020 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/fetrEMnCl5Zh_fQOWvWOnhZ-n6Fmr7tG5bBQ--8VkyFfhThtLWxNEJjmKVVwXIO9Sea4UG3e_Sw5e4UYH0iJ3g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:25:58.582058 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/fetrEMnCl5Zh_fQOWvWOnhZ-n6Fmr7tG5bBQ--8VkyFfhThtLWxNEJjmKVVwXIO9Sea4UG3e_Sw5e4UYH0iJ3g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:25:58 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/fetrEMnCl5Zh_fQOWvWOnhZ-n6Fmr7tG5bBQ--8VkyFfhThtLWxNEJjmKVVwXIO9Sea4UG3e_Sw5e4UYH0iJ3g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:25:58 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/fetrEMnCl5Zh_fQOWvWOnhZ-n6Fmr7tG5bBQ--8VkyFfhThtLWxNEJjmKVVwXIO9Sea4UG3e_Sw5e4UYH0iJ3g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:25:58 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/02 02:25:58 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for transfers to finish 2025/09/02 02:26:00 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/02 02:26:00 INFO : yaml: Copied (new) 2025/09/02 02:26:00 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje': not deleting files as there were IO errors 2025/09/02 02:26:00 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje': not deleting directories as there were IO errors sync_test.go:1658: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1658 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (8.51s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/09/02 02:26:03 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Making map for --track-renames 2025/09/02 02:26:03 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Finished making map for --track-renames 2025/09/02 02:26:03 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for checks to finish 2025/09/02 02:26:03 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/02 02:26:03 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/09/02 02:26:03 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for renames to finish 2025/09/02 02:26:03 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for transfers to finish 2025/09/02 02:26:05 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/02 02:26:05 INFO : potato: Copied (new) 2025/09/02 02:26:05 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/02 02:26:05 INFO : sub/yam: Copied (new) 2025/09/02 02:26:05 DEBUG : Waiting for deletions to finish 2025/09/02 02:26:07 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/02 02:26:07 DEBUG : potato: Unchanged skipping 2025/09/02 02:26:07 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Making map for --track-renames 2025/09/02 02:26:07 INFO : proton drive root link ID 'rclone-test-lopoduy9keje': Finished making map for --track-renames 2025/09/02 02:26:07 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for checks to finish 2025/09/02 02:26:07 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for renames to finish 2025/09/02 02:26:08.539603 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/V4jsRUG5WS7RF9xDqhbxbwofk9TDbXkIyhESXH8ETWQQWC49Oe9p5FwJdD_LnQ0SkTDuaVyy7MdXbqMgfo2tLw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:26:08.539639 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/V4jsRUG5WS7RF9xDqhbxbwofk9TDbXkIyhESXH8ETWQQWC49Oe9p5FwJdD_LnQ0SkTDuaVyy7MdXbqMgfo2tLw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:08 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/V4jsRUG5WS7RF9xDqhbxbwofk9TDbXkIyhESXH8ETWQQWC49Oe9p5FwJdD_LnQ0SkTDuaVyy7MdXbqMgfo2tLw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:08 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/V4jsRUG5WS7RF9xDqhbxbwofk9TDbXkIyhESXH8ETWQQWC49Oe9p5FwJdD_LnQ0SkTDuaVyy7MdXbqMgfo2tLw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:08 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/02 02:26:08 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for transfers to finish 2025/09/02 02:26:10 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/02 02:26:10 INFO : yam: Copied (new) 2025/09/02 02:26:10 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje': not deleting files as there were IO errors 2025/09/02 02:26:10 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje': not deleting directories as there were IO errors sync_test.go:1697: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1697 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (10.73s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:26:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/02 02:26:13 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/09/02 02:26:13 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for checks to finish 2025/09/02 02:26:13 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Waiting for transfers to finish 2025/09/02 02:26:18 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/09/02 02:26:18 INFO : nested/sub dir/file: Copied (new) 2025/09/02 02:26:18 INFO : nested/sub dir/file: Deleted 2025/09/02 02:26:18 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/02 02:26:18 INFO : sub dir/hello world: Copied (new) 2025/09/02 02:26:18 INFO : sub dir/hello world: Deleted 2025/09/02 02:26:18 ERROR : Local file system at /tmp/rclone874869990: not deleting directories as there were IO errors sync_test.go:1818: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1818 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (8.55s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:26:22 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-boyoluw3yoye" 2025/09/02 02:26:22 DEBUG : proton drive root link ID 'rclone-test-boyoluw3yoye': Has cached credentials 2025/09/02 02:26:23 DEBUG : proton drive root link ID 'rclone-test-boyoluw3yoye': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-lopoduy9keje' -> proton drive root link ID 'rclone-test-boyoluw3yoye' 2025/09/02 02:26:34 DEBUG : proton drive root link ID 'rclone-test-boyoluw3yoye': Using server-side directory move 2025/09/02 02:26:34 INFO : proton drive root link ID 'rclone-test-boyoluw3yoye': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/02 02:26:35 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/02 02:26:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/02 02:26:35 DEBUG : empty space: Unchanged skipping 2025/09/02 02:26:35 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/02 02:26:35 DEBUG : proton drive root link ID 'rclone-test-boyoluw3yoye': Waiting for checks to finish 2025/09/02 02:26:35 INFO : empty space: Deleted 2025/09/02 02:26:35 DEBUG : proton drive root link ID 'rclone-test-boyoluw3yoye': Waiting for transfers to finish 2025/09/02 02:26:36 INFO : potato3: Deleted 2025/09/02 02:26:36.294136 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/HyS4WBIkNITPaPznp7eez8G8NDcr9vL9-i9GKDfUkUG-9CDltgjp-YviCd6d3t5zeTeYQRZtNRXgsO85OUPdwQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:26:36.294175 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/HyS4WBIkNITPaPznp7eez8G8NDcr9vL9-i9GKDfUkUG-9CDltgjp-YviCd6d3t5zeTeYQRZtNRXgsO85OUPdwQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:36 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/HyS4WBIkNITPaPznp7eez8G8NDcr9vL9-i9GKDfUkUG-9CDltgjp-YviCd6d3t5zeTeYQRZtNRXgsO85OUPdwQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:36.598138 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_3NBOQKNWQnRL5IGDqVqVUUT85vPH5bJLGnw8wzsnTv5sGqlUEKVL59STUhZHNgBqJIUHz2BnBgK9NE0JXwnRw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:26:36.598171 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_3NBOQKNWQnRL5IGDqVqVUUT85vPH5bJLGnw8wzsnTv5sGqlUEKVL59STUhZHNgBqJIUHz2BnBgK9NE0JXwnRw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:36 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_3NBOQKNWQnRL5IGDqVqVUUT85vPH5bJLGnw8wzsnTv5sGqlUEKVL59STUhZHNgBqJIUHz2BnBgK9NE0JXwnRw==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1911 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_3NBOQKNWQnRL5IGDqVqVUUT85vPH5bJLGnw8wzsnTv5sGqlUEKVL59STUhZHNgBqJIUHz2BnBgK9NE0JXwnRw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/09/02 02:26:36 DEBUG : proton drive root link ID 'rclone-test-boyoluw3yoye': Purge remote --- FAIL: TestServerSideMove (17.15s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:26:39 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-huxacum2reva" 2025/09/02 02:26:39 DEBUG : proton drive root link ID 'rclone-test-huxacum2reva': Has cached credentials 2025/09/02 02:26:40 DEBUG : proton drive root link ID 'rclone-test-huxacum2reva': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-lopoduy9keje' -> proton drive root link ID 'rclone-test-huxacum2reva' 2025/09/02 02:26:51 DEBUG : empty space: Excluded (Size Filter) 2025/09/02 02:26:51 DEBUG : empty space: Excluded 2025/09/02 02:26:52 DEBUG : empty space: Excluded (Size Filter) 2025/09/02 02:26:52 DEBUG : empty space: Excluded 2025/09/02 02:26:52 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/02 02:26:52 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/02 02:26:52 DEBUG : proton drive root link ID 'rclone-test-huxacum2reva': Waiting for checks to finish 2025/09/02 02:26:52 DEBUG : proton drive root link ID 'rclone-test-huxacum2reva': Waiting for transfers to finish 2025/09/02 02:26:52 INFO : potato3: Deleted 2025/09/02 02:26:52.987131 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Gs5BT8p5Exm1QOne32zo1gsT0ALUSsfnVT1sdUwshoQSmXroU-WKpf77plvbrhcIKR1Vb7efvxETbVhDQ4JHNA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:26:52.987165 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Gs5BT8p5Exm1QOne32zo1gsT0ALUSsfnVT1sdUwshoQSmXroU-WKpf77plvbrhcIKR1Vb7efvxETbVhDQ4JHNA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:52 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Gs5BT8p5Exm1QOne32zo1gsT0ALUSsfnVT1sdUwshoQSmXroU-WKpf77plvbrhcIKR1Vb7efvxETbVhDQ4JHNA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:53.369892 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/dmZL-AtwYqiP92ejC3x6GhpIA8IAbREWDOtso6s_Z1QaKEXFpAelg0602-7FmXKBIe9NOY7SbAbx_4l8ZBFKXQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:26:53.369934 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/dmZL-AtwYqiP92ejC3x6GhpIA8IAbREWDOtso6s_Z1QaKEXFpAelg0602-7FmXKBIe9NOY7SbAbx_4l8ZBFKXQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:26:53 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/dmZL-AtwYqiP92ejC3x6GhpIA8IAbREWDOtso6s_Z1QaKEXFpAelg0602-7FmXKBIe9NOY7SbAbx_4l8ZBFKXQ==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1924 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/dmZL-AtwYqiP92ejC3x6GhpIA8IAbREWDOtso6s_Z1QaKEXFpAelg0602-7FmXKBIe9NOY7SbAbx_4l8ZBFKXQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/09/02 02:26:53 DEBUG : proton drive root link ID 'rclone-test-huxacum2reva': Purge remote --- FAIL: TestServerSideMoveWithFilter (17.44s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:26:56 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-dibicas2fequ" 2025/09/02 02:26:56 DEBUG : proton drive root link ID 'rclone-test-dibicas2fequ': Has cached credentials 2025/09/02 02:26:58 DEBUG : proton drive root link ID 'rclone-test-dibicas2fequ': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:27:03 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-lopoduy9keje' -> proton drive root link ID 'rclone-test-dibicas2fequ' 2025/09/02 02:27:10 DEBUG : proton drive root link ID 'rclone-test-dibicas2fequ': Using server-side directory move 2025/09/02 02:27:10 INFO : proton drive root link ID 'rclone-test-dibicas2fequ': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/02 02:27:11 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/02 02:27:11 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/02 02:27:11 DEBUG : empty space: Unchanged skipping 2025/09/02 02:27:11 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/02 02:27:11 DEBUG : proton drive root link ID 'rclone-test-dibicas2fequ': Waiting for checks to finish 2025/09/02 02:27:11 INFO : potato3: Deleted 2025/09/02 02:27:11 INFO : empty space: Deleted 2025/09/02 02:27:11 DEBUG : proton drive root link ID 'rclone-test-dibicas2fequ': Waiting for transfers to finish 2025/09/02 02:27:11.830889 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/OqXcKKPPMXdRPDLa7I-Hr2LgvNpADdX7l640PyeraGVBPVfsE8YqlC4_96jBU7-kcQF69gaRjQO27XVYDsE58w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:27:11.830968 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/OqXcKKPPMXdRPDLa7I-Hr2LgvNpADdX7l640PyeraGVBPVfsE8YqlC4_96jBU7-kcQF69gaRjQO27XVYDsE58w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:27:11 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/OqXcKKPPMXdRPDLa7I-Hr2LgvNpADdX7l640PyeraGVBPVfsE8YqlC4_96jBU7-kcQF69gaRjQO27XVYDsE58w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:27:12.136991 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f3goQSWacRCn5fmgz8Isajnxp6Kliq9oEcnXxcNb46ZJZ0gX28-IVYY5cVrBSRfxK5osoAVTDhFV6zpO1gwnLA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:27:12.137022 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f3goQSWacRCn5fmgz8Isajnxp6Kliq9oEcnXxcNb46ZJZ0gX28-IVYY5cVrBSRfxK5osoAVTDhFV6zpO1gwnLA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:27:12 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f3goQSWacRCn5fmgz8Isajnxp6Kliq9oEcnXxcNb46ZJZ0gX28-IVYY5cVrBSRfxK5osoAVTDhFV6zpO1gwnLA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:27:12 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje': not deleting directories as there were IO errors sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1931 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2025/09/02 02:27:12 DEBUG : proton drive root link ID 'rclone-test-dibicas2fequ': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (18.79s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:27:22 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/dst" 2025/09/02 02:27:22 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Has cached credentials 2025/09/02 02:27:23 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:29:31 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/backup" 2025/09/02 02:29:31 DEBUG : Config file has changed externally - reloading 2025/09/02 02:29:31 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/backup': Has cached credentials 2025/09/02 02:29:33 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/backup': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:33:07 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/02 02:33:07 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Waiting for checks to finish 2025/09/02 02:33:07 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/02 02:33:07 DEBUG : two: Unchanged skipping 2025/09/02 02:33:09.067766 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Na0oidQfYkq943cuG5aO6gDiDaVG0U5fUhG6wC7RMy49CncMg4zRYTBl9KxU51D5ZPRDulEzXml5BqRXuGD5EA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:33:09.067810 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Na0oidQfYkq943cuG5aO6gDiDaVG0U5fUhG6wC7RMy49CncMg4zRYTBl9KxU51D5ZPRDulEzXml5BqRXuGD5EA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:33:09 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Na0oidQfYkq943cuG5aO6gDiDaVG0U5fUhG6wC7RMy49CncMg4zRYTBl9KxU51D5ZPRDulEzXml5BqRXuGD5EA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:33:09 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Waiting for transfers to finish 2025/09/02 02:33:09 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje/dst': not deleting files as there were IO errors 2025/09/02 02:33:09 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2457 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Na0oidQfYkq943cuG5aO6gDiDaVG0U5fUhG6wC7RMy49CncMg4zRYTBl9KxU51D5ZPRDulEzXml5BqRXuGD5EA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (357.62s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:33:20 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/dst" 2025/09/02 02:33:20 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Has cached credentials 2025/09/02 02:33:21 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:35:10 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/backup" 2025/09/02 02:35:10 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/backup': Has cached credentials 2025/09/02 02:35:11 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/backup': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:38:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/02 02:38:56 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/02 02:38:56 DEBUG : two: Unchanged skipping 2025/09/02 02:38:56 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Waiting for checks to finish 2025/09/02 02:38:58.439272 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Lt6Wrgwnjns_ItqQo5f9_wBuAjz5427JRbIDedq0F-5RBT_vm7M_ZWg2AIo_FxOaYYkRqKgsnGzBGXiCppbl5Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:38:58.439299 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Lt6Wrgwnjns_ItqQo5f9_wBuAjz5427JRbIDedq0F-5RBT_vm7M_ZWg2AIo_FxOaYYkRqKgsnGzBGXiCppbl5Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:38:58 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Lt6Wrgwnjns_ItqQo5f9_wBuAjz5427JRbIDedq0F-5RBT_vm7M_ZWg2AIo_FxOaYYkRqKgsnGzBGXiCppbl5Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:38:58 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Waiting for transfers to finish 2025/09/02 02:38:58 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje/dst': not deleting files as there were IO errors 2025/09/02 02:38:58 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2461 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Lt6Wrgwnjns_ItqQo5f9_wBuAjz5427JRbIDedq0F-5RBT_vm7M_ZWg2AIo_FxOaYYkRqKgsnGzBGXiCppbl5Q==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (349.34s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:39:09 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/dst" 2025/09/02 02:39:09 DEBUG : Config file has changed externally - reloading 2025/09/02 02:39:09 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Has cached credentials 2025/09/02 02:39:10 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:41:06 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/backup" 2025/09/02 02:41:06 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/backup': Has cached credentials 2025/09/02 02:41:07 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/backup': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:44:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/02 02:44:56 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Waiting for checks to finish 2025/09/02 02:44:56 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/02 02:44:56 DEBUG : two: Unchanged skipping 2025/09/02 02:44:58.675833 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DFwF55VyWI5LpSJpXsoQ6Y-pQnUjAwi6Ffr0D0e6MZHVMXn0CASm303clpWb04W3nmx4jBlbOQvRFTb8nbBRHw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:44:58.675871 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DFwF55VyWI5LpSJpXsoQ6Y-pQnUjAwi6Ffr0D0e6MZHVMXn0CASm303clpWb04W3nmx4jBlbOQvRFTb8nbBRHw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:44:58 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DFwF55VyWI5LpSJpXsoQ6Y-pQnUjAwi6Ffr0D0e6MZHVMXn0CASm303clpWb04W3nmx4jBlbOQvRFTb8nbBRHw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:44:58 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Waiting for transfers to finish 2025/09/02 02:44:58 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje/dst': not deleting files as there were IO errors 2025/09/02 02:44:58 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2465 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DFwF55VyWI5LpSJpXsoQ6Y-pQnUjAwi6Ffr0D0e6MZHVMXn0CASm303clpWb04W3nmx4jBlbOQvRFTb8nbBRHw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (360.22s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:45:09 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/dst" 2025/09/02 02:45:09 DEBUG : Config file has changed externally - reloading 2025/09/02 02:45:09 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Has cached credentials 2025/09/02 02:45:11 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:47:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/02 02:47:00 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Waiting for checks to finish 2025/09/02 02:47:00 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/02 02:47:00 DEBUG : two: Unchanged skipping 2025/09/02 02:47:01.521273 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/S1iKpeJ1tzZhNBoNm0HSyg0XjEkhemRjH9aWgJANl7ESNkRxc-PDMDAIkExGBxpnBBJ9Zl1yl2hHikUMFebYJg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:47:01.521297 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/S1iKpeJ1tzZhNBoNm0HSyg0XjEkhemRjH9aWgJANl7ESNkRxc-PDMDAIkExGBxpnBBJ9Zl1yl2hHikUMFebYJg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:47:01 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/S1iKpeJ1tzZhNBoNm0HSyg0XjEkhemRjH9aWgJANl7ESNkRxc-PDMDAIkExGBxpnBBJ9Zl1yl2hHikUMFebYJg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:47:01 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Waiting for transfers to finish 2025/09/02 02:47:01 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje/dst': not deleting files as there were IO errors 2025/09/02 02:47:01 ERROR : proton drive root link ID 'rclone-test-lopoduy9keje/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2469 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/S1iKpeJ1tzZhNBoNm0HSyg0XjEkhemRjH9aWgJANl7ESNkRxc-PDMDAIkExGBxpnBBJ9Zl1yl2hHikUMFebYJg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (122.36s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:47:12 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/dst" 2025/09/02 02:47:12 DEBUG : Config file has changed externally - reloading 2025/09/02 02:47:12 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Has cached credentials 2025/09/02 02:47:13 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:49:08 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/02 02:49:09.398822 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/baUyL7nJBqRTVDQxsbe7jV3XkBMGpsRA-bS8oUggqNdroI1oVToh5Btn9moPl1aESlxuFWbRIgvZmVhv5Wk0ug==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:49:09.398868 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/baUyL7nJBqRTVDQxsbe7jV3XkBMGpsRA-bS8oUggqNdroI1oVToh5Btn9moPl1aESlxuFWbRIgvZmVhv5Wk0ug==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:49:09 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/baUyL7nJBqRTVDQxsbe7jV3XkBMGpsRA-bS8oUggqNdroI1oVToh5Btn9moPl1aESlxuFWbRIgvZmVhv5Wk0ug==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2553 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/baUyL7nJBqRTVDQxsbe7jV3XkBMGpsRA-bS8oUggqNdroI1oVToh5Btn9moPl1aESlxuFWbRIgvZmVhv5Wk0ug==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (127.81s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-lopoduy9keje'", Local "Local file system at /tmp/rclone874869990", Modify Window "1s" 2025/09/02 02:49:20 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lopoduy9keje/dst" 2025/09/02 02:49:20 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Has cached credentials 2025/09/02 02:49:21 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje/dst': Used cached credential to initialize the ProtonDrive API 2025/09/02 02:51:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/02 02:51:19.594929 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/efjSkSzMubCclC1AedhtC2rZsFb0LCwbQvn_ISpilRud4wT572ABJGPFX3-OAue4PlS-aUdqXAJB2cGBw3P6LA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/02 02:51:19.594963 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/efjSkSzMubCclC1AedhtC2rZsFb0LCwbQvn_ISpilRud4wT572ABJGPFX3-OAue4PlS-aUdqXAJB2cGBw3P6LA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/02 02:51:19 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/efjSkSzMubCclC1AedhtC2rZsFb0LCwbQvn_ISpilRud4wT572ABJGPFX3-OAue4PlS-aUdqXAJB2cGBw3P6LA==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/efjSkSzMubCclC1AedhtC2rZsFb0LCwbQvn_ISpilRud4wT572ABJGPFX3-OAue4PlS-aUdqXAJB2cGBw3P6LA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (130.16s) FAIL 2025/09/02 02:51:23 DEBUG : proton drive root link ID 'rclone-test-lopoduy9keje': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 26m1.012464534s (try 3/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]