"./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 5/5) 2025/08/11 05:35:29 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi" 2025/08/11 05:35:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/11 05:35:29 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Has cached credentials 2025/08/11 05:35:30 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:35:30 DEBUG : Creating backend with remote "/tmp/rclone2764803858" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:35:33 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-dipacex7qeze" 2025/08/11 05:35:33 DEBUG : proton drive root link ID 'rclone-test-dipacex7qeze': Has cached credentials 2025/08/11 05:35:34 DEBUG : proton drive root link ID 'rclone-test-dipacex7qeze': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-hujikez6gupi' -> proton drive root link ID 'rclone-test-dipacex7qeze' 2025/08/11 05:35:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/11 05:35:34 DEBUG : proton drive root link ID 'rclone-test-dipacex7qeze': Waiting for checks to finish 2025/08/11 05:35:34 DEBUG : proton drive root link ID 'rclone-test-dipacex7qeze': Waiting for transfers to finish 2025/08/11 05:35:37 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/08/11 05:35:37 INFO : sub dir/hello world: Copied (new) 2025/08/11 05:35:38.547975 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/08/11 05:35:38.548018 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/08/11 05:35:40 DEBUG : proton drive root link ID 'rclone-test-dipacex7qeze': Using server-side directory move 2025/08/11 05:35:40 INFO : proton drive root link ID 'rclone-test-dipacex7qeze': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/08/11 05:35:40 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/08/11 05:35:40 DEBUG : proton drive root link ID 'rclone-test-dipacex7qeze': Waiting for checks to finish 2025/08/11 05:35:40 DEBUG : proton drive root link ID 'rclone-test-dipacex7qeze': Waiting for transfers to finish 2025/08/11 05:35:41 INFO : sub dir/hello world: Deleted 2025/08/11 05:35:41.672445 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UcDV1hLLZX_opKbVqjuFNPQVa5-xSQ20qjQCw_w9c_wjx37-sv2du1Eq21SZcC9IJbrXjfAG3OxKdmee6R3NIg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:35:41.672521 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UcDV1hLLZX_opKbVqjuFNPQVa5-xSQ20qjQCw_w9c_wjx37-sv2du1Eq21SZcC9IJbrXjfAG3OxKdmee6R3NIg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:41 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UcDV1hLLZX_opKbVqjuFNPQVa5-xSQ20qjQCw_w9c_wjx37-sv2du1Eq21SZcC9IJbrXjfAG3OxKdmee6R3NIg==/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/UcDV1hLLZX_opKbVqjuFNPQVa5-xSQ20qjQCw_w9c_wjx37-sv2du1Eq21SZcC9IJbrXjfAG3OxKdmee6R3NIg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/08/11 05:35:41 DEBUG : proton drive root link ID 'rclone-test-dipacex7qeze': Purge remote --- FAIL: TestServerSideMoveOverSelf (12.33s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/08/11 05:35:43 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Making map for --track-renames 2025/08/11 05:35:43 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Finished making map for --track-renames 2025/08/11 05:35:43 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for checks to finish 2025/08/11 05:35:43 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for renames to finish 2025/08/11 05:35:43 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/08/11 05:35:43 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/08/11 05:35:43 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for transfers to finish 2025/08/11 05:35:44 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/08/11 05:35:44 INFO : potato: Copied (new) 2025/08/11 05:35:44 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/11 05:35:44 INFO : yam: Copied (new) 2025/08/11 05:35:44 DEBUG : Waiting for deletions to finish 2025/08/11 05:35:45 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/11 05:35:45 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Making map for --track-renames 2025/08/11 05:35:45 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Finished making map for --track-renames 2025/08/11 05:35:45 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for checks to finish 2025/08/11 05:35:45 DEBUG : potato: Unchanged skipping 2025/08/11 05:35:45 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for renames to finish 2025/08/11 05:35:45.808289 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lGPa0mh5j_gAUIi7X8EQLgB7A8z221QbOXICphXn0lY21P9gaHO7usrPjlL0pD0bzKagSFXasDq7h5JLnBfvSg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:35:45.808328 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lGPa0mh5j_gAUIi7X8EQLgB7A8z221QbOXICphXn0lY21P9gaHO7usrPjlL0pD0bzKagSFXasDq7h5JLnBfvSg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:45 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lGPa0mh5j_gAUIi7X8EQLgB7A8z221QbOXICphXn0lY21P9gaHO7usrPjlL0pD0bzKagSFXasDq7h5JLnBfvSg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:45 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lGPa0mh5j_gAUIi7X8EQLgB7A8z221QbOXICphXn0lY21P9gaHO7usrPjlL0pD0bzKagSFXasDq7h5JLnBfvSg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:45 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/08/11 05:35:45 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for transfers to finish 2025/08/11 05:35:47 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/11 05:35:47 INFO : yaml: Copied (new) 2025/08/11 05:35:47 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi': not deleting files as there were IO errors 2025/08/11 05:35:47 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi': 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 (5.20s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/08/11 05:35:48 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Making map for --track-renames 2025/08/11 05:35:48 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Finished making map for --track-renames 2025/08/11 05:35:48 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for checks to finish 2025/08/11 05:35:48 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/08/11 05:35:48 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/08/11 05:35:48 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for renames to finish 2025/08/11 05:35:48 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for transfers to finish 2025/08/11 05:35:49 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/08/11 05:35:49 INFO : potato: Copied (new) 2025/08/11 05:35:49 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/11 05:35:49 INFO : yam: Copied (new) 2025/08/11 05:35:49 DEBUG : Waiting for deletions to finish 2025/08/11 05:35:50 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/11 05:35:50 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Making map for --track-renames 2025/08/11 05:35:50 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Finished making map for --track-renames 2025/08/11 05:35:50 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for checks to finish 2025/08/11 05:35:50 DEBUG : potato: Unchanged skipping 2025/08/11 05:35:50 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for renames to finish 2025/08/11 05:35:50.940402 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DteSzRfzOA8Z1WNr-kFj7g3SBuZ8FgMDei_V6E0JcsrLAKEKlue65oNnJY2gbDREJJq2swoisARfcvFQ01Ri3w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:35:50.940443 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DteSzRfzOA8Z1WNr-kFj7g3SBuZ8FgMDei_V6E0JcsrLAKEKlue65oNnJY2gbDREJJq2swoisARfcvFQ01Ri3w==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:50 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DteSzRfzOA8Z1WNr-kFj7g3SBuZ8FgMDei_V6E0JcsrLAKEKlue65oNnJY2gbDREJJq2swoisARfcvFQ01Ri3w==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:50 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DteSzRfzOA8Z1WNr-kFj7g3SBuZ8FgMDei_V6E0JcsrLAKEKlue65oNnJY2gbDREJJq2swoisARfcvFQ01Ri3w==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:50 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/08/11 05:35:50 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for transfers to finish 2025/08/11 05:35:52 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/11 05:35:52 INFO : yaml: Copied (new) 2025/08/11 05:35:52 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi': not deleting files as there were IO errors 2025/08/11 05:35:52 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi': 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 (5.14s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/08/11 05:35:53 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Making map for --track-renames 2025/08/11 05:35:53 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Finished making map for --track-renames 2025/08/11 05:35:53 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for checks to finish 2025/08/11 05:35:53 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/08/11 05:35:53 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/08/11 05:35:53 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for renames to finish 2025/08/11 05:35:53 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for transfers to finish 2025/08/11 05:35:55 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/08/11 05:35:55 INFO : potato: Copied (new) 2025/08/11 05:35:55 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/11 05:35:55 INFO : sub/yam: Copied (new) 2025/08/11 05:35:55 DEBUG : Waiting for deletions to finish 2025/08/11 05:35:56 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/11 05:35:56 DEBUG : potato: Unchanged skipping 2025/08/11 05:35:56 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Making map for --track-renames 2025/08/11 05:35:56 INFO : proton drive root link ID 'rclone-test-hujikez6gupi': Finished making map for --track-renames 2025/08/11 05:35:56 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for checks to finish 2025/08/11 05:35:56 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for renames to finish 2025/08/11 05:35:56.806434 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_gbBn_MSQnpT9Z6JUroiexnazPc_XKaJsn1XekMNxpUimughsdJdIw2_rRTgy8wTDpaMh5lV9IBfz4ZULIeXYQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:35:56.806527 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_gbBn_MSQnpT9Z6JUroiexnazPc_XKaJsn1XekMNxpUimughsdJdIw2_rRTgy8wTDpaMh5lV9IBfz4ZULIeXYQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:56 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_gbBn_MSQnpT9Z6JUroiexnazPc_XKaJsn1XekMNxpUimughsdJdIw2_rRTgy8wTDpaMh5lV9IBfz4ZULIeXYQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:56 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_gbBn_MSQnpT9Z6JUroiexnazPc_XKaJsn1XekMNxpUimughsdJdIw2_rRTgy8wTDpaMh5lV9IBfz4ZULIeXYQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:35:56 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/08/11 05:35:56 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for transfers to finish 2025/08/11 05:35:58 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/11 05:35:58 INFO : yam: Copied (new) 2025/08/11 05:35:58 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi': not deleting files as there were IO errors 2025/08/11 05:35:58 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi': 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 (6.32s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:35:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/11 05:35:59 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/08/11 05:35:59 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for checks to finish 2025/08/11 05:35:59 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': Waiting for transfers to finish 2025/08/11 05:36:02 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/08/11 05:36:02 INFO : sub dir/hello world: Copied (new) 2025/08/11 05:36:02 INFO : sub dir/hello world: Deleted 2025/08/11 05:36:02 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/08/11 05:36:02 INFO : nested/sub dir/file: Copied (new) 2025/08/11 05:36:02 INFO : nested/sub dir/file: Deleted 2025/08/11 05:36:02 ERROR : Local file system at /tmp/rclone2764803858: 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 (4.39s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:36:04 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-famuluf7puku" 2025/08/11 05:36:04 DEBUG : proton drive root link ID 'rclone-test-famuluf7puku': Has cached credentials 2025/08/11 05:36:04 DEBUG : proton drive root link ID 'rclone-test-famuluf7puku': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hujikez6gupi' -> proton drive root link ID 'rclone-test-famuluf7puku' 2025/08/11 05:36:13 DEBUG : proton drive root link ID 'rclone-test-famuluf7puku': Using server-side directory move 2025/08/11 05:36:13 INFO : proton drive root link ID 'rclone-test-famuluf7puku': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/08/11 05:36:13 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/11 05:36:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/08/11 05:36:13 DEBUG : empty space: Unchanged skipping 2025/08/11 05:36:13 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/11 05:36:13 DEBUG : proton drive root link ID 'rclone-test-famuluf7puku': Waiting for checks to finish 2025/08/11 05:36:13 INFO : empty space: Deleted 2025/08/11 05:36:13 DEBUG : proton drive root link ID 'rclone-test-famuluf7puku': Waiting for transfers to finish 2025/08/11 05:36:13 INFO : potato3: Deleted 2025/08/11 05:36:13.896233 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hiaalceOK6quXArb7zdXizZ3PDDQeelf-Evm77gZcBqA4VY1mc_PgU4o-Ni_bVvlIPs_QMPZSGqD_-oh3zu2hg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:36:13.896278 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hiaalceOK6quXArb7zdXizZ3PDDQeelf-Evm77gZcBqA4VY1mc_PgU4o-Ni_bVvlIPs_QMPZSGqD_-oh3zu2hg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:13 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hiaalceOK6quXArb7zdXizZ3PDDQeelf-Evm77gZcBqA4VY1mc_PgU4o-Ni_bVvlIPs_QMPZSGqD_-oh3zu2hg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:14.011370 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vOpg3IHDCoYi02nJ8YQgW8kDU5EGR3i6h8ZovD4dXKByMsa2Y1iSICdB_GQ-H3y0CHgrlSntZ1z7Nn5-cGtzpg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:36:14.011422 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vOpg3IHDCoYi02nJ8YQgW8kDU5EGR3i6h8ZovD4dXKByMsa2Y1iSICdB_GQ-H3y0CHgrlSntZ1z7Nn5-cGtzpg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:14 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vOpg3IHDCoYi02nJ8YQgW8kDU5EGR3i6h8ZovD4dXKByMsa2Y1iSICdB_GQ-H3y0CHgrlSntZ1z7Nn5-cGtzpg==/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/vOpg3IHDCoYi02nJ8YQgW8kDU5EGR3i6h8ZovD4dXKByMsa2Y1iSICdB_GQ-H3y0CHgrlSntZ1z7Nn5-cGtzpg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/08/11 05:36:14 DEBUG : proton drive root link ID 'rclone-test-famuluf7puku': Purge remote --- FAIL: TestServerSideMove (11.19s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:36:15 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-yurupuv0navo" 2025/08/11 05:36:15 DEBUG : proton drive root link ID 'rclone-test-yurupuv0navo': Has cached credentials 2025/08/11 05:36:16 DEBUG : proton drive root link ID 'rclone-test-yurupuv0navo': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hujikez6gupi' -> proton drive root link ID 'rclone-test-yurupuv0navo' 2025/08/11 05:36:24 DEBUG : empty space: Excluded (Size Filter) 2025/08/11 05:36:24 DEBUG : empty space: Excluded 2025/08/11 05:36:24 DEBUG : empty space: Excluded (Size Filter) 2025/08/11 05:36:24 DEBUG : empty space: Excluded 2025/08/11 05:36:24 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/11 05:36:24 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/11 05:36:24 DEBUG : proton drive root link ID 'rclone-test-yurupuv0navo': Waiting for checks to finish 2025/08/11 05:36:24 DEBUG : proton drive root link ID 'rclone-test-yurupuv0navo': Waiting for transfers to finish 2025/08/11 05:36:24 INFO : potato3: Deleted 2025/08/11 05:36:24.629268 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XHRZTGdYIjBV9QEb1x1zGSODu4tshIB9NalpXo3QLkzz-GNg5lDqXx0WDcqnvn5sLk7JXOG9Xo3c8LPz-3Yk4g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:36:24.629311 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XHRZTGdYIjBV9QEb1x1zGSODu4tshIB9NalpXo3QLkzz-GNg5lDqXx0WDcqnvn5sLk7JXOG9Xo3c8LPz-3Yk4g==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:24 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XHRZTGdYIjBV9QEb1x1zGSODu4tshIB9NalpXo3QLkzz-GNg5lDqXx0WDcqnvn5sLk7JXOG9Xo3c8LPz-3Yk4g==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:24.787892 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/myKfUlzt-LriISrtyqhMFBE-uQGpqFA7NVi6F48itjOgAZON-K3a0k7z-QWGRsksAejshJOw6_sXvplI7Jz7Cg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:36:24.787941 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/myKfUlzt-LriISrtyqhMFBE-uQGpqFA7NVi6F48itjOgAZON-K3a0k7z-QWGRsksAejshJOw6_sXvplI7Jz7Cg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:24 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/myKfUlzt-LriISrtyqhMFBE-uQGpqFA7NVi6F48itjOgAZON-K3a0k7z-QWGRsksAejshJOw6_sXvplI7Jz7Cg==/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/myKfUlzt-LriISrtyqhMFBE-uQGpqFA7NVi6F48itjOgAZON-K3a0k7z-QWGRsksAejshJOw6_sXvplI7Jz7Cg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/08/11 05:36:24 DEBUG : proton drive root link ID 'rclone-test-yurupuv0navo': Purge remote --- FAIL: TestServerSideMoveWithFilter (11.05s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:36:26 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sepuxic9panu" 2025/08/11 05:36:26 DEBUG : proton drive root link ID 'rclone-test-sepuxic9panu': Has cached credentials 2025/08/11 05:36:27 DEBUG : proton drive root link ID 'rclone-test-sepuxic9panu': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:36:31 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hujikez6gupi' -> proton drive root link ID 'rclone-test-sepuxic9panu' 2025/08/11 05:36:35 DEBUG : proton drive root link ID 'rclone-test-sepuxic9panu': Using server-side directory move 2025/08/11 05:36:35 INFO : proton drive root link ID 'rclone-test-sepuxic9panu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/08/11 05:36:35 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/11 05:36:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/08/11 05:36:35 DEBUG : empty space: Unchanged skipping 2025/08/11 05:36:35 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/11 05:36:35 DEBUG : proton drive root link ID 'rclone-test-sepuxic9panu': Waiting for checks to finish 2025/08/11 05:36:36 INFO : potato3: Deleted 2025/08/11 05:36:36 INFO : empty space: Deleted 2025/08/11 05:36:36 DEBUG : proton drive root link ID 'rclone-test-sepuxic9panu': Waiting for transfers to finish 2025/08/11 05:36:36.333320 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nXl5LzsstO1uQknIrwuR379xPMxAJiqtW-pHORF_1jRl0vMPxIWgyWFN4Z61xnzNsD48UHM_HSAq22oSDps7aA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:36:36.333366 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nXl5LzsstO1uQknIrwuR379xPMxAJiqtW-pHORF_1jRl0vMPxIWgyWFN4Z61xnzNsD48UHM_HSAq22oSDps7aA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:36 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nXl5LzsstO1uQknIrwuR379xPMxAJiqtW-pHORF_1jRl0vMPxIWgyWFN4Z61xnzNsD48UHM_HSAq22oSDps7aA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:36.495799 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/I9sELMz4OZuFG9XJq0j2C5aazUpQRwJVzdiJ8o7jsXONjtlKiihcy46GWa-gVQz99p6wyTMkm2GqdHnBmt6LBA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:36:36.495839 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/I9sELMz4OZuFG9XJq0j2C5aazUpQRwJVzdiJ8o7jsXONjtlKiihcy46GWa-gVQz99p6wyTMkm2GqdHnBmt6LBA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:36 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/I9sELMz4OZuFG9XJq0j2C5aazUpQRwJVzdiJ8o7jsXONjtlKiihcy46GWa-gVQz99p6wyTMkm2GqdHnBmt6LBA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:36:36 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi': 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/08/11 05:36:36 DEBUG : proton drive root link ID 'rclone-test-sepuxic9panu': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (11.72s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:36:43 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/dst" 2025/08/11 05:36:43 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Has cached credentials 2025/08/11 05:36:44 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:38:21 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/backup" 2025/08/11 05:38:21 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/backup': Has cached credentials 2025/08/11 05:38:22 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/backup': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:41:38 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/11 05:41:38 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Waiting for checks to finish 2025/08/11 05:41:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/11 05:41:38 DEBUG : two: Unchanged skipping 2025/08/11 05:41:38.973520 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1nyPH67-geqhbmxzp2_yQMA-zYNQl32cHVkvXgVFBAlI2RT3vjqQZF5Vvl4dqLqMLcV--Za1vrcb3i2aLa8wvw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:41:38.973553 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1nyPH67-geqhbmxzp2_yQMA-zYNQl32cHVkvXgVFBAlI2RT3vjqQZF5Vvl4dqLqMLcV--Za1vrcb3i2aLa8wvw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:41:38 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1nyPH67-geqhbmxzp2_yQMA-zYNQl32cHVkvXgVFBAlI2RT3vjqQZF5Vvl4dqLqMLcV--Za1vrcb3i2aLa8wvw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:41:38 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Waiting for transfers to finish 2025/08/11 05:41:38 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi/dst': not deleting files as there were IO errors 2025/08/11 05:41:38 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi/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/1nyPH67-geqhbmxzp2_yQMA-zYNQl32cHVkvXgVFBAlI2RT3vjqQZF5Vvl4dqLqMLcV--Za1vrcb3i2aLa8wvw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (302.80s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:41:46 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/dst" 2025/08/11 05:41:46 DEBUG : Config file has changed externally - reloading 2025/08/11 05:41:46 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Has cached credentials 2025/08/11 05:41:46 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:43:25 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/backup" 2025/08/11 05:43:25 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/backup': Has cached credentials 2025/08/11 05:43:26 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/backup': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:46:41 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/11 05:46:41 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/11 05:46:41 DEBUG : two: Unchanged skipping 2025/08/11 05:46:41 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Waiting for checks to finish 2025/08/11 05:46:42.855230 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oSZ8Auyefw-GH7gak4DWv_oDPUr1hTewjmamp4VQVaTsA9yZOxpWov_FDi_zgV_jfESdeCs6UgCXj3aT_T-dyw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:46:42.855275 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oSZ8Auyefw-GH7gak4DWv_oDPUr1hTewjmamp4VQVaTsA9yZOxpWov_FDi_zgV_jfESdeCs6UgCXj3aT_T-dyw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:46:42 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oSZ8Auyefw-GH7gak4DWv_oDPUr1hTewjmamp4VQVaTsA9yZOxpWov_FDi_zgV_jfESdeCs6UgCXj3aT_T-dyw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:46:42 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Waiting for transfers to finish 2025/08/11 05:46:42 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi/dst': not deleting files as there were IO errors 2025/08/11 05:46:42 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi/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/oSZ8Auyefw-GH7gak4DWv_oDPUr1hTewjmamp4VQVaTsA9yZOxpWov_FDi_zgV_jfESdeCs6UgCXj3aT_T-dyw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (303.89s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:46:49 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/dst" 2025/08/11 05:46:49 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Has cached credentials 2025/08/11 05:46:50 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:48:29 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/backup" 2025/08/11 05:48:29 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/backup': Has cached credentials 2025/08/11 05:48:30 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/backup': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:51:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/11 05:51:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/11 05:51:48 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Waiting for checks to finish 2025/08/11 05:51:48 DEBUG : two: Unchanged skipping 2025/08/11 05:51:48.968306 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GCoOgQ9xlRMJ8LInpnC_2I7Lnr8D1V5ovcZKLzpoLSpdKk3lffqtZ-CT5uyovB0Rzd_kt1fc1qKq31T__BeFmg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:51:48.968340 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GCoOgQ9xlRMJ8LInpnC_2I7Lnr8D1V5ovcZKLzpoLSpdKk3lffqtZ-CT5uyovB0Rzd_kt1fc1qKq31T__BeFmg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:51:48 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GCoOgQ9xlRMJ8LInpnC_2I7Lnr8D1V5ovcZKLzpoLSpdKk3lffqtZ-CT5uyovB0Rzd_kt1fc1qKq31T__BeFmg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:51:48 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Waiting for transfers to finish 2025/08/11 05:51:48 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi/dst': not deleting files as there were IO errors 2025/08/11 05:51:48 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi/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/GCoOgQ9xlRMJ8LInpnC_2I7Lnr8D1V5ovcZKLzpoLSpdKk3lffqtZ-CT5uyovB0Rzd_kt1fc1qKq31T__BeFmg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (306.12s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:51:55 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/dst" 2025/08/11 05:51:55 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Has cached credentials 2025/08/11 05:51:56 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:53:39 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/11 05:53:39 DEBUG : two: Unchanged skipping 2025/08/11 05:53:39 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Waiting for checks to finish 2025/08/11 05:53:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/11 05:53:40.310837 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/yKAqkf4m0rHr0l2shLSlArcWuIvxadsmTYrS5dn7lam1eRCOsISXMBi4ovzfDx2AiccaQB-Ea9kxPwnHdIdg2w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:53:40.310886 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/yKAqkf4m0rHr0l2shLSlArcWuIvxadsmTYrS5dn7lam1eRCOsISXMBi4ovzfDx2AiccaQB-Ea9kxPwnHdIdg2w==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:53:40 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/yKAqkf4m0rHr0l2shLSlArcWuIvxadsmTYrS5dn7lam1eRCOsISXMBi4ovzfDx2AiccaQB-Ea9kxPwnHdIdg2w==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:53:40 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Waiting for transfers to finish 2025/08/11 05:53:40 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi/dst': not deleting files as there were IO errors 2025/08/11 05:53:40 ERROR : proton drive root link ID 'rclone-test-hujikez6gupi/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/yKAqkf4m0rHr0l2shLSlArcWuIvxadsmTYrS5dn7lam1eRCOsISXMBi4ovzfDx2AiccaQB-Ea9kxPwnHdIdg2w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (111.01s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:53:46 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/dst" 2025/08/11 05:53:46 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Has cached credentials 2025/08/11 05:53:47 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:55:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/11 05:55:30.196963 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bPYyRYJt5YEvvrqDPVFRl3bj_w55prx24lJZPQIxBfzDya00-hFQSKW1JtSFkp2_OP-r2Wqmr9rbdWPdmUHPMQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:55:30.197000 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bPYyRYJt5YEvvrqDPVFRl3bj_w55prx24lJZPQIxBfzDya00-hFQSKW1JtSFkp2_OP-r2Wqmr9rbdWPdmUHPMQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:55:30 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bPYyRYJt5YEvvrqDPVFRl3bj_w55prx24lJZPQIxBfzDya00-hFQSKW1JtSFkp2_OP-r2Wqmr9rbdWPdmUHPMQ==/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/bPYyRYJt5YEvvrqDPVFRl3bj_w55prx24lJZPQIxBfzDya00-hFQSKW1JtSFkp2_OP-r2Wqmr9rbdWPdmUHPMQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (109.92s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-hujikez6gupi'", Local "Local file system at /tmp/rclone2764803858", Modify Window "1s" 2025/08/11 05:55:36 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hujikez6gupi/dst" 2025/08/11 05:55:36 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Has cached credentials 2025/08/11 05:55:37 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi/dst': Used cached credential to initialize the ProtonDrive API 2025/08/11 05:57:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/11 05:57:19.043213 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zELm5ggGqsGJhOtrLUQwe7DzftJIqhPKZpACZnaK-scdeCsk28j1FqphoraWhS4ivDWjVT7ywqcFoHVrz72Q0Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/11 05:57:19.043262 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zELm5ggGqsGJhOtrLUQwe7DzftJIqhPKZpACZnaK-scdeCsk28j1FqphoraWhS4ivDWjVT7ywqcFoHVrz72Q0Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/11 05:57:19 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zELm5ggGqsGJhOtrLUQwe7DzftJIqhPKZpACZnaK-scdeCsk28j1FqphoraWhS4ivDWjVT7ywqcFoHVrz72Q0Q==/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/zELm5ggGqsGJhOtrLUQwe7DzftJIqhPKZpACZnaK-scdeCsk28j1FqphoraWhS4ivDWjVT7ywqcFoHVrz72Q0Q==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (108.81s) FAIL 2025/08/11 05:57:20 DEBUG : proton drive root link ID 'rclone-test-hujikez6gupi': 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 21m51.477023517s (try 5/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]