"./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestCopyOverSelf|TestMoveOverSelf|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterChangingModtimeOnly|TestSyncCompareDest|TestSyncDoesntUpdateModtime|TestSyncIgnoreTimes|TestSyncUTFNorm|TestSyncWithUpdateOlder)$'" - Starting (try 4/5) 2024/10/30 03:46:47 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajuqal8kefa" 2024/10/30 03:46:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/30 03:46:47 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Has cached credentials 2024/10/30 03:46:49 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Used cached credential to initialize the ProtonDrive API 2024/10/30 03:46:49 DEBUG : Creating backend with remote "/tmp/rclone1927343786" === RUN TestCopyOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:46:54 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 03:46:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2024/10/30 03:46:54 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 03:46:54.977804 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 03:46:54.977844 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 03:47:07.743381 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/v6Q4y-aT1diII2s7rqZMTpAHD3t1D2zLXmRGz5Ees5a-Hc0zf3aITSqTBFkTgl6QxFscwKhUr3TlXVLcmOH4bw==/revisions/HyvAaFiaFcPexSfWW8eWYwNkMkMiwgHUjf9Skf8FqMFlwu-GFY2fjLPXxVOwceVvj3OPYO1h3F3op9o35oGR6A==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:47:07Z" level=warning msg="Too many requests, retrying after delay" after=8 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/v6Q4y-aT1diII2s7rqZMTpAHD3t1D2zLXmRGz5Ees5a-Hc0zf3aITSqTBFkTgl6QxFscwKhUr3TlXVLcmOH4bw==/revisions/HyvAaFiaFcPexSfWW8eWYwNkMkMiwgHUjf9Skf8FqMFlwu-GFY2fjLPXxVOwceVvj3OPYO1h3F3op9o35oGR6A==" 2024/10/30 03:48:16.109293 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/v6Q4y-aT1diII2s7rqZMTpAHD3t1D2zLXmRGz5Ees5a-Hc0zf3aITSqTBFkTgl6QxFscwKhUr3TlXVLcmOH4bw==/revisions/HyvAaFiaFcPexSfWW8eWYwNkMkMiwgHUjf9Skf8FqMFlwu-GFY2fjLPXxVOwceVvj3OPYO1h3F3op9o35oGR6A==: Internal server error (Code=500, Status=500), Attempt 2 2024/10/30 03:48:16.109323 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/v6Q4y-aT1diII2s7rqZMTpAHD3t1D2zLXmRGz5Ees5a-Hc0zf3aITSqTBFkTgl6QxFscwKhUr3TlXVLcmOH4bw==/revisions/HyvAaFiaFcPexSfWW8eWYwNkMkMiwgHUjf9Skf8FqMFlwu-GFY2fjLPXxVOwceVvj3OPYO1h3F3op9o35oGR6A==: Internal server error (Code=500, Status=500) 2024/10/30 03:48:16 ERROR : sub dir/hello world: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/v6Q4y-aT1diII2s7rqZMTpAHD3t1D2zLXmRGz5Ees5a-Hc0zf3aITSqTBFkTgl6QxFscwKhUr3TlXVLcmOH4bw==/revisions/HyvAaFiaFcPexSfWW8eWYwNkMkMiwgHUjf9Skf8FqMFlwu-GFY2fjLPXxVOwceVvj3OPYO1h3F3op9o35oGR6A==: Internal server error (Code=500, Status=500) sync_test.go:611: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:611 Error: Received unexpected error: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/v6Q4y-aT1diII2s7rqZMTpAHD3t1D2zLXmRGz5Ees5a-Hc0zf3aITSqTBFkTgl6QxFscwKhUr3TlXVLcmOH4bw==/revisions/HyvAaFiaFcPexSfWW8eWYwNkMkMiwgHUjf9Skf8FqMFlwu-GFY2fjLPXxVOwceVvj3OPYO1h3F3op9o35oGR6A==: Internal server error (Code=500, Status=500) Test: TestCopyOverSelf 2024/10/30 03:48:28.203250 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/BcE167j3XpTHh27X92fkrSVzqa4fs80jJqrzVBpdjaVk7YcOG9pxwwPN-Id-wcONzbf99mgQyAcFJKU9yKwkhA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:48:28Z" level=warning msg="Too many requests, retrying after delay" after=6 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/BcE167j3XpTHh27X92fkrSVzqa4fs80jJqrzVBpdjaVk7YcOG9pxwwPN-Id-wcONzbf99mgQyAcFJKU9yKwkhA==/trash_multiple" 2024/10/30 03:48:44.545786 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/BcE167j3XpTHh27X92fkrSVzqa4fs80jJqrzVBpdjaVk7YcOG9pxwwPN-Id-wcONzbf99mgQyAcFJKU9yKwkhA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T03:48:44Z" level=warning msg="Too many requests, retrying after delay" after=13 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/BcE167j3XpTHh27X92fkrSVzqa4fs80jJqrzVBpdjaVk7YcOG9pxwwPN-Id-wcONzbf99mgQyAcFJKU9yKwkhA==/trash_multiple" --- FAIL: TestCopyOverSelf (128.97s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:49:02 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lufabuf8bezi" 2024/10/30 03:49:02 DEBUG : Config file has changed externally - reloading 2024/10/30 03:49:02 DEBUG : proton drive root link ID 'rclone-test-lufabuf8bezi': Has cached credentials 2024/10/30 03:49:03 DEBUG : proton drive root link ID 'rclone-test-lufabuf8bezi': Used cached credential to initialize the ProtonDrive API sync_test.go:626: Server side copy (if possible) proton drive root link ID 'rclone-test-tajuqal8kefa' -> proton drive root link ID 'rclone-test-lufabuf8bezi' 2024/10/30 03:49:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/10/30 03:49:04 DEBUG : proton drive root link ID 'rclone-test-lufabuf8bezi': Waiting for checks to finish 2024/10/30 03:49:04 DEBUG : proton drive root link ID 'rclone-test-lufabuf8bezi': Waiting for transfers to finish 2024/10/30 03:49:08 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/10/30 03:49:08 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:49:10.589525 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 03:49:10.589545 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 03:49:22.615979 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:49:22Z" level=warning msg="Too many requests, retrying after delay" after=7 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==" 2024/10/30 03:49:40.034267 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T03:49:40Z" level=warning msg="Too many requests, retrying after delay" after=7 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==" 2024/10/30 03:49:57.724846 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T03:49:57Z" level=warning msg="Too many requests, retrying after delay" after=7 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==" 2024/10/30 03:50:15.308576 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 03:50:15.308593 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==: Resource is busy. Please try again (Code=2502, Status=429) run.go:285: Failed to put "sub dir/hello world" to "proton drive root link ID 'rclone-test-tajuqal8kefa'": 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/P_B7M6s8KYKyGMJ09ubOWgPg2m3zLfgzCATZp_SONQsWgXg6sbHYs6_3wLE0pwqPvWDkJxZz5gH6w9SLvMYjnQ==/revisions/Jd_nDBK5aJ7KpZjuVox73LBZuspnvRMgtzXY2UxbGg6XTDqsCwH_cE-6rfDRTMhh0f7t-v5G8mkCFnJWTbE-RA==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 03:50:15 DEBUG : proton drive root link ID 'rclone-test-lufabuf8bezi': Purge remote --- FAIL: TestServerSideCopyOverSelf (80.25s) === RUN TestMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:50:23 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2024/10/30 03:50:23 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 03:50:23 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 03:50:23.435145 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 03:50:23.435167 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 03:51:25.286443 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/uO4Ogj1i1NaoqDtdWG60SkD4GMdNzIZoTwwkEkHgjq1TxR_G0-hrtvL-tHi0ysPbVC9JgIi1Ar04g4iVNAOWnA==/revisions/suI9CGLaC9RwmuuMCQ41m9ZnTvtZg4_ZMJ0FUO5S41Xa1XZIWQgLaOQUmBEWbfJFdR-N341mGa8Rg6vHvSI9wA==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 03:51:25.286474 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/uO4Ogj1i1NaoqDtdWG60SkD4GMdNzIZoTwwkEkHgjq1TxR_G0-hrtvL-tHi0ysPbVC9JgIi1Ar04g4iVNAOWnA==/revisions/suI9CGLaC9RwmuuMCQ41m9ZnTvtZg4_ZMJ0FUO5S41Xa1XZIWQgLaOQUmBEWbfJFdR-N341mGa8Rg6vHvSI9wA==: Internal server error (Code=500, Status=500) 2024/10/30 03:51:25 ERROR : sub dir/hello world: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/uO4Ogj1i1NaoqDtdWG60SkD4GMdNzIZoTwwkEkHgjq1TxR_G0-hrtvL-tHi0ysPbVC9JgIi1Ar04g4iVNAOWnA==/revisions/suI9CGLaC9RwmuuMCQ41m9ZnTvtZg4_ZMJ0FUO5S41Xa1XZIWQgLaOQUmBEWbfJFdR-N341mGa8Rg6vHvSI9wA==: Internal server error (Code=500, Status=500) 2024/10/30 03:51:25 ERROR : sub dir/hello world: Not deleting source as copy failed: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/uO4Ogj1i1NaoqDtdWG60SkD4GMdNzIZoTwwkEkHgjq1TxR_G0-hrtvL-tHi0ysPbVC9JgIi1Ar04g4iVNAOWnA==/revisions/suI9CGLaC9RwmuuMCQ41m9ZnTvtZg4_ZMJ0FUO5S41Xa1XZIWQgLaOQUmBEWbfJFdR-N341mGa8Rg6vHvSI9wA==: Internal server error (Code=500, Status=500) sync_test.go:655: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:655 Error: Received unexpected error: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/uO4Ogj1i1NaoqDtdWG60SkD4GMdNzIZoTwwkEkHgjq1TxR_G0-hrtvL-tHi0ysPbVC9JgIi1Ar04g4iVNAOWnA==/revisions/suI9CGLaC9RwmuuMCQ41m9ZnTvtZg4_ZMJ0FUO5S41Xa1XZIWQgLaOQUmBEWbfJFdR-N341mGa8Rg6vHvSI9wA==: Internal server error (Code=500, Status=500) Test: TestMoveOverSelf 2024/10/30 03:51:37.039167 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/_YvMDUnQbvmobxUl-DbJD_Vnc4QTJJPYGDIoOzt2TNriIiRo-Mk8ajPMuOC4L_heA6vsVZN_ZX1Vw8vrYuVNwg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:51:37Z" level=warning msg="Too many requests, retrying after delay" after=7 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/_YvMDUnQbvmobxUl-DbJD_Vnc4QTJJPYGDIoOzt2TNriIiRo-Mk8ajPMuOC4L_heA6vsVZN_ZX1Vw8vrYuVNwg==/trash_multiple" 2024/10/30 03:51:55.065864 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/_YvMDUnQbvmobxUl-DbJD_Vnc4QTJJPYGDIoOzt2TNriIiRo-Mk8ajPMuOC4L_heA6vsVZN_ZX1Vw8vrYuVNwg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T03:51:55Z" level=warning msg="Too many requests, retrying after delay" after=8 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/_YvMDUnQbvmobxUl-DbJD_Vnc4QTJJPYGDIoOzt2TNriIiRo-Mk8ajPMuOC4L_heA6vsVZN_ZX1Vw8vrYuVNwg==/trash_multiple" --- FAIL: TestMoveOverSelf (105.22s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:52:07 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-jezafuj6yoli" 2024/10/30 03:52:07 DEBUG : Config file has changed externally - reloading 2024/10/30 03:52:07 DEBUG : proton drive root link ID 'rclone-test-jezafuj6yoli': Has cached credentials 2024/10/30 03:52:08 DEBUG : proton drive root link ID 'rclone-test-jezafuj6yoli': Used cached credential to initialize the ProtonDrive API sync_test.go:671: Server side copy (if possible) proton drive root link ID 'rclone-test-tajuqal8kefa' -> proton drive root link ID 'rclone-test-jezafuj6yoli' 2024/10/30 03:52:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/10/30 03:52:10 DEBUG : proton drive root link ID 'rclone-test-jezafuj6yoli': Waiting for checks to finish 2024/10/30 03:52:10 DEBUG : proton drive root link ID 'rclone-test-jezafuj6yoli': Waiting for transfers to finish 2024/10/30 03:52:14 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/10/30 03:52:14 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:52:16.155295 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 03:52:16.155331 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 03:53:18.054058 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/8c2uvq58WfjHcRPp9z4PmzWUxizqiBIQK7P_BM26QB-FF1YzMctNrqAYgm6qeRA0HA4l2vdSQoA7FNL0j_9BJA==/revisions/PGIWl_SaykuHIb9cZqSqO8Zb9gBIIXuynV8S2ZGCF-6vdM-S0Yxp4ybtbBzSgzsr5Kx5xT-_PAYsy1aKC1_BpQ==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 03:53:18.054090 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/8c2uvq58WfjHcRPp9z4PmzWUxizqiBIQK7P_BM26QB-FF1YzMctNrqAYgm6qeRA0HA4l2vdSQoA7FNL0j_9BJA==/revisions/PGIWl_SaykuHIb9cZqSqO8Zb9gBIIXuynV8S2ZGCF-6vdM-S0Yxp4ybtbBzSgzsr5Kx5xT-_PAYsy1aKC1_BpQ==: Internal server error (Code=500, Status=500) run.go:285: Failed to put "sub dir/hello world" to "proton drive root link ID 'rclone-test-tajuqal8kefa'": 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/8c2uvq58WfjHcRPp9z4PmzWUxizqiBIQK7P_BM26QB-FF1YzMctNrqAYgm6qeRA0HA4l2vdSQoA7FNL0j_9BJA==/revisions/PGIWl_SaykuHIb9cZqSqO8Zb9gBIIXuynV8S2ZGCF-6vdM-S0Yxp4ybtbBzSgzsr5Kx5xT-_PAYsy1aKC1_BpQ==: Internal server error (Code=500, Status=500) 2024/10/30 03:53:18 DEBUG : proton drive root link ID 'rclone-test-jezafuj6yoli': Purge remote 2024/10/30 03:53:31.000045 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/65LGPlOa86RlQCxywmitm4CZsTbhNs0M0BeGpNFM4DFOsPH--E7kcKCDL0BGPH3OE_7q-XM5XJ9xbXq5x-Kgaw==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:53:31Z" level=warning msg="Too many requests, retrying after delay" after=12 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/65LGPlOa86RlQCxywmitm4CZsTbhNs0M0BeGpNFM4DFOsPH--E7kcKCDL0BGPH3OE_7q-XM5XJ9xbXq5x-Kgaw==/trash_multiple" 2024/10/30 03:53:53.510688 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/65LGPlOa86RlQCxywmitm4CZsTbhNs0M0BeGpNFM4DFOsPH--E7kcKCDL0BGPH3OE_7q-XM5XJ9xbXq5x-Kgaw==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T03:53:53Z" level=warning msg="Too many requests, retrying after delay" after=11 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/65LGPlOa86RlQCxywmitm4CZsTbhNs0M0BeGpNFM4DFOsPH--E7kcKCDL0BGPH3OE_7q-XM5XJ9xbXq5x-Kgaw==/trash_multiple" --- FAIL: TestServerSideMoveOverSelf (122.23s) === RUN TestSyncIgnoreTimes run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:54:09 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 03:54:09 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/10/30 03:54:09 DEBUG : existing: Unchanged skipping 2024/10/30 03:54:09 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 03:54:09 DEBUG : Waiting for deletions to finish 2024/10/30 03:54:09 INFO : There was nothing to transfer run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:54:09 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/10/30 03:54:09 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 03:54:09 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 03:54:09.941181 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 03:54:09.941202 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 03:54:22.158087 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:54:22Z" level=warning msg="Too many requests, retrying after delay" after=10 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==" 2024/10/30 03:54:42.721613 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T03:54:42Z" level=warning msg="Too many requests, retrying after delay" after=8 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==" 2024/10/30 03:55:01.022540 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T03:55:01Z" level=warning msg="Too many requests, retrying after delay" after=13 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==" 2024/10/30 03:55:24.493670 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 03:55:24.493692 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 03:55:24 ERROR : existing: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 03:55:24 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting files as there were IO errors 2024/10/30 03:55:24 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting directories as there were IO errors sync_test.go:875: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:875 Error: Received unexpected error: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/hvhK5T7G1GVYF4YSlRHGjKtamhz26It-bKokh5a9BkAJIXb6r4MbKvP7FH0EzG_X5flz7oey2NE3dKXe0S6bAQ==/revisions/spRwgPD7v9GnJVe1SzvxfTKQnx-hWg-eT6b1Jo7QFDvXZXcBec8MGVu9pPqx57m64EdELqnU5vjxrFNvxcW2Eg==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestSyncIgnoreTimes --- FAIL: TestSyncIgnoreTimes (80.03s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:55:29 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 +0000 2024/10/30 03:55:29 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 03:55:29 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/10/30 03:55:29 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2024/10/30 03:55:29 DEBUG : empty space: Unchanged skipping 2024/10/30 03:55:29 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 03:55:29 DEBUG : Waiting for deletions to finish 2024/10/30 03:55:29 INFO : There was nothing to transfer run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:55:30 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 03:55:30 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 +0000 2024/10/30 03:55:30 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/10/30 03:55:30 NOTICE: proton drive root link ID 'rclone-test-tajuqal8kefa': Forced to upload files to set modification times on this backend. 2024/10/30 03:55:30 INFO : empty space: src and dst identical but can't set mod time without re-uploading 2024/10/30 03:55:30 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 03:55:30.429074 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 03:55:30.429107 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 03:55:43.047538 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/d0v664lfe4Taqch8HlvJFiq_3p----EP0CDAp-2oP_HDJsEfHZrvFLWLmM8kq6RSVzWePMeigK_3o_fxl_Nx6A==/revisions/VK2loTByFSOCNZbsWKkYupkYhszKVaajx2RYi1Cx7IQQorCX7jdkjuaFgneC9Jr7EOAuhYtDO5bvWv9dsQHwGQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:55:43Z" level=warning msg="Too many requests, retrying after delay" after=5 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/d0v664lfe4Taqch8HlvJFiq_3p----EP0CDAp-2oP_HDJsEfHZrvFLWLmM8kq6RSVzWePMeigK_3o_fxl_Nx6A==/revisions/VK2loTByFSOCNZbsWKkYupkYhszKVaajx2RYi1Cx7IQQorCX7jdkjuaFgneC9Jr7EOAuhYtDO5bvWv9dsQHwGQ==" 2024/10/30 03:56:48.419629 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/d0v664lfe4Taqch8HlvJFiq_3p----EP0CDAp-2oP_HDJsEfHZrvFLWLmM8kq6RSVzWePMeigK_3o_fxl_Nx6A==/revisions/VK2loTByFSOCNZbsWKkYupkYhszKVaajx2RYi1Cx7IQQorCX7jdkjuaFgneC9Jr7EOAuhYtDO5bvWv9dsQHwGQ==: Internal server error (Code=500, Status=500), Attempt 2 2024/10/30 03:56:48.419655 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/d0v664lfe4Taqch8HlvJFiq_3p----EP0CDAp-2oP_HDJsEfHZrvFLWLmM8kq6RSVzWePMeigK_3o_fxl_Nx6A==/revisions/VK2loTByFSOCNZbsWKkYupkYhszKVaajx2RYi1Cx7IQQorCX7jdkjuaFgneC9Jr7EOAuhYtDO5bvWv9dsQHwGQ==: Internal server error (Code=500, Status=500) 2024/10/30 03:56:48 ERROR : empty space: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/d0v664lfe4Taqch8HlvJFiq_3p----EP0CDAp-2oP_HDJsEfHZrvFLWLmM8kq6RSVzWePMeigK_3o_fxl_Nx6A==/revisions/VK2loTByFSOCNZbsWKkYupkYhszKVaajx2RYi1Cx7IQQorCX7jdkjuaFgneC9Jr7EOAuhYtDO5bvWv9dsQHwGQ==: Internal server error (Code=500, Status=500) 2024/10/30 03:56:48 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting files as there were IO errors 2024/10/30 03:56:48 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting directories as there were IO errors sync_test.go:1003: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1003 Error: Received unexpected error: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/d0v664lfe4Taqch8HlvJFiq_3p----EP0CDAp-2oP_HDJsEfHZrvFLWLmM8kq6RSVzWePMeigK_3o_fxl_Nx6A==/revisions/VK2loTByFSOCNZbsWKkYupkYhszKVaajx2RYi1Cx7IQQorCX7jdkjuaFgneC9Jr7EOAuhYtDO5bvWv9dsQHwGQ==: Internal server error (Code=500, Status=500) Test: TestSyncAfterChangingModtimeOnly 2024/10/30 03:57:00.338765 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:57:00Z" level=warning msg="Too many requests, retrying after delay" after=9 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple" 2024/10/30 03:57:20.326757 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T03:57:20Z" level=warning msg="Too many requests, retrying after delay" after=13 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple" --- FAIL: TestSyncAfterChangingModtimeOnly (128.18s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:57:37 DEBUG : foo: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 +0000 2024/10/30 03:57:37 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 03:57:37 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone1927343786) 2024/10/30 03:57:37 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (proton drive root link ID 'rclone-test-tajuqal8kefa') 2024/10/30 03:57:37 DEBUG : foo: sha1 differ 2024/10/30 03:57:37 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 03:57:37.723661 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 03:57:37.723688 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 03:57:49.816181 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T03:57:49Z" level=warning msg="Too many requests, retrying after delay" after=10 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==" 2024/10/30 03:58:10.169630 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T03:58:10Z" level=warning msg="Too many requests, retrying after delay" after=5 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==" 2024/10/30 03:58:25.939325 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T03:58:25Z" level=warning msg="Too many requests, retrying after delay" after=12 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==" 2024/10/30 03:58:49.075655 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 03:58:49.075671 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 03:58:49 ERROR : foo: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 03:58:49 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting files as there were IO errors 2024/10/30 03:58:49 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting directories as there were IO errors sync_test.go:1054: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1054 Error: Received unexpected error: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/sfzfQ1xqkbYUomqFKtgjHmauv-nTojqT7SsAWDiWX2OOcTMQdZejIYFtowwwcRS3SF04Z-ZlNmeKtE0WCBEb6A==/revisions/P5Ft_92EfsQbQFKRJNSFM8IeWFBTWswzT7aXpaXqp-ImDZ65bVVEg4aAKkdvOlUVERr2XxLUKfT00i69_zOxpA==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestSyncDoesntUpdateModtime --- FAIL: TestSyncDoesntUpdateModtime (75.80s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 03:58:53 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 03:58:53 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/10/30 03:58:53 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 03:58:53.320559 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 03:58:53.320577 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 03:59:55.363858 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/JFenaL_-HPNNI_GaD39cHeZLbHOEfjA95rPrnwCB7KCkqan9kgEaNbT5sfCPgFx7zOmBf4pAi8FlyMnCDerbow==/revisions/7ChGGc6TcatsVbIiJdmQeB5zBo1um4KFCHNf4Y2rNyfgnYhUeift0GveWSH7HorRaXYr8dYrsgvKZoIRu6G5hA==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 03:59:55.363894 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/JFenaL_-HPNNI_GaD39cHeZLbHOEfjA95rPrnwCB7KCkqan9kgEaNbT5sfCPgFx7zOmBf4pAi8FlyMnCDerbow==/revisions/7ChGGc6TcatsVbIiJdmQeB5zBo1um4KFCHNf4Y2rNyfgnYhUeift0GveWSH7HorRaXYr8dYrsgvKZoIRu6G5hA==: Internal server error (Code=500, Status=500) 2024/10/30 03:59:55 ERROR : potato: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/JFenaL_-HPNNI_GaD39cHeZLbHOEfjA95rPrnwCB7KCkqan9kgEaNbT5sfCPgFx7zOmBf4pAi8FlyMnCDerbow==/revisions/7ChGGc6TcatsVbIiJdmQeB5zBo1um4KFCHNf4Y2rNyfgnYhUeift0GveWSH7HorRaXYr8dYrsgvKZoIRu6G5hA==: Internal server error (Code=500, Status=500) 2024/10/30 03:59:55 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting files as there were IO errors 2024/10/30 03:59:55 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting directories as there were IO errors sync_test.go:1093: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1093 Error: Received unexpected error: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/JFenaL_-HPNNI_GaD39cHeZLbHOEfjA95rPrnwCB7KCkqan9kgEaNbT5sfCPgFx7zOmBf4pAi8FlyMnCDerbow==/revisions/7ChGGc6TcatsVbIiJdmQeB5zBo1um4KFCHNf4Y2rNyfgnYhUeift0GveWSH7HorRaXYr8dYrsgvKZoIRu6G5hA==: Internal server error (Code=500, Status=500) Test: TestSyncAfterChangingFilesSizeOnly 2024/10/30 04:00:06.953319 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:00:06Z" level=warning msg="Too many requests, retrying after delay" after=6 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple" 2024/10/30 04:00:23.984305 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:00:23Z" level=warning msg="Too many requests, retrying after delay" after=6 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple" --- FAIL: TestSyncAfterChangingFilesSizeOnly (104.90s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 04:00:38 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 +0000 2024/10/30 04:00:38 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 04:00:38 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone1927343786) 2024/10/30 04:00:38 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (proton drive root link ID 'rclone-test-tajuqal8kefa') 2024/10/30 04:00:38 DEBUG : potato: sha1 differ 2024/10/30 04:00:38 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 04:00:38.348754 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 04:00:38.348780 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 04:01:40.247557 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-xSfWmfDtKsF8SdUvfAsL-8dMx6cJVBYXXnB69YSdFhemM-zsUneY7IE5TygNWr2kBHY77rkCWas3p698F-WdA==/revisions/ssP8y_M790FXUyZ0Lgklf3fPsb2Fs4o82u37QS_CmBk5rHhXlXUiDEbODKPlW6m0UZX6WX6i8Ym3YO_Kpz0FWQ==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 04:01:40.247590 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-xSfWmfDtKsF8SdUvfAsL-8dMx6cJVBYXXnB69YSdFhemM-zsUneY7IE5TygNWr2kBHY77rkCWas3p698F-WdA==/revisions/ssP8y_M790FXUyZ0Lgklf3fPsb2Fs4o82u37QS_CmBk5rHhXlXUiDEbODKPlW6m0UZX6WX6i8Ym3YO_Kpz0FWQ==: Internal server error (Code=500, Status=500) 2024/10/30 04:01:40 ERROR : potato: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-xSfWmfDtKsF8SdUvfAsL-8dMx6cJVBYXXnB69YSdFhemM-zsUneY7IE5TygNWr2kBHY77rkCWas3p698F-WdA==/revisions/ssP8y_M790FXUyZ0Lgklf3fPsb2Fs4o82u37QS_CmBk5rHhXlXUiDEbODKPlW6m0UZX6WX6i8Ym3YO_Kpz0FWQ==: Internal server error (Code=500, Status=500) 2024/10/30 04:01:40 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting files as there were IO errors 2024/10/30 04:01:40 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting directories as there were IO errors sync_test.go:1118: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1118 Error: Received unexpected error: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-xSfWmfDtKsF8SdUvfAsL-8dMx6cJVBYXXnB69YSdFhemM-zsUneY7IE5TygNWr2kBHY77rkCWas3p698F-WdA==/revisions/ssP8y_M790FXUyZ0Lgklf3fPsb2Fs4o82u37QS_CmBk5rHhXlXUiDEbODKPlW6m0UZX6WX6i8Ym3YO_Kpz0FWQ==: Internal server error (Code=500, Status=500) Test: TestSyncAfterChangingContentsOnly 2024/10/30 04:01:51.741639 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:01:51Z" level=warning msg="Too many requests, retrying after delay" after=14 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple" 2024/10/30 04:02:16.909561 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:02:16Z" level=warning msg="Too many requests, retrying after delay" after=6 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple" --- FAIL: TestSyncAfterChangingContentsOnly (108.16s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 04:02:31 DEBUG : five: Need to transfer - File not found at Destination 2024/10/30 04:02:31 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/10/30 04:02:31 DEBUG : three: Sizes identical 2024/10/30 04:02:31 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 04:02:31 DEBUG : one: Destination is newer than source, skipping 2024/10/30 04:02:31 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/10/30 04:02:31 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone1927343786) 2024/10/30 04:02:31 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (proton drive root link ID 'rclone-test-tajuqal8kefa') 2024/10/30 04:02:31 DEBUG : two: sha1 differ 2024/10/30 04:02:31 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/10/30 04:02:31 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 04:02:32.294474 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 04:02:32.294497 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 04:02:32.353505 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 04:02:32.353541 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 04:02:33 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/10/30 04:02:33 INFO : five: Copied (new) 2024/10/30 04:02:45.014006 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:02:45Z" level=warning msg="Too many requests, retrying after delay" after=13 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==" 2024/10/30 04:02:45.014846 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:02:45Z" level=warning msg="Too many requests, retrying after delay" after=9 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==" 2024/10/30 04:03:04.391910 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:03:04Z" level=warning msg="Too many requests, retrying after delay" after=8 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==" 2024/10/30 04:03:08.966001 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:03:08Z" level=warning msg="Too many requests, retrying after delay" after=6 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==" 2024/10/30 04:03:22.875490 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T04:03:22Z" level=warning msg="Too many requests, retrying after delay" after=5 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==" 2024/10/30 04:03:25.593886 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T04:03:25Z" level=warning msg="Too many requests, retrying after delay" after=6 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==" 2024/10/30 04:03:38.478308 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 04:03:38.478326 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:03:38 ERROR : four: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/kPulyj34V6Zk1tVuYEEs2N9--6RFej3Pwuv-RDfaYq8ADpsT9XZl_XdpBwb_E9boLmUbtVXQx27zpbOjQDx6Rg==/revisions/cTF7KwGs2PlpZWLPW6DULt30K3nYfxWnm44nVTJQVyU-xHW33tn2Pht5eD6DoJLI_dxTk16v_gCdhAfYsuSvQQ==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:03:42.474631 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 04:03:42.474647 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:03:42 ERROR : two: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:03:42 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting files as there were IO errors 2024/10/30 04:03:42 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting directories as there were IO errors sync_test.go:1444: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1444 Error: Received unexpected error: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rVRj43KCTQ9bIlLSaJAT8EqjNgAp0o9KN9S7nEXwMJct4vg_ivASNRdvvJDVGXPZb92zEqJW6SkuCPuQPxn3dg==/revisions/Zkntak_tKytkDp2dDOyBhlFKaac2GxQh_BCvsx1KRxeQBApCnDCHFdrOo0M9gmd_NAvmS4oJGnQBIJWP7tc61w==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestSyncWithUpdateOlder --- FAIL: TestSyncWithUpdateOlder (82.67s) === RUN TestSyncCompareDest run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 04:03:46 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajuqal8kefa/dst" 2024/10/30 04:03:46 DEBUG : Config file has changed externally - reloading 2024/10/30 04:03:46 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa/dst': Has cached credentials 2024/10/30 04:03:47 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa/dst': Used cached credential to initialize the ProtonDrive API 2024/10/30 04:05:22 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajuqal8kefa/CompareDest" 2024/10/30 04:05:22 DEBUG : Config file has changed externally - reloading 2024/10/30 04:05:22 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa/CompareDest': Has cached credentials 2024/10/30 04:05:23 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa/CompareDest': Used cached credential to initialize the ProtonDrive API 2024/10/30 04:06:58 DEBUG : one: Need to transfer - File not found at Destination 2024/10/30 04:06:58 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa/dst': Waiting for checks to finish 2024/10/30 04:06:58 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa/dst': Waiting for transfers to finish 2024/10/30 04:07:00 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/10/30 04:07:00 INFO : one: Copied (new) 2024/10/30 04:07:00 DEBUG : Waiting for deletions to finish 2024/10/30 04:07:02 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/10/30 04:07:02 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa/dst': Waiting for checks to finish 2024/10/30 04:07:03 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa/dst': Waiting for transfers to finish 2024/10/30 04:07:03.419435 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 04:07:03.419463 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 04:07:16.139639 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/Xrkp-wkgWTuIVBl0Hq3o-pkRyqVIDcIAd5MAAvmpcWsAURtZKf8SEuyj8uROD6s2UFgmdyf6q4h7rpVtDYTUWw==/revisions/BFCO4WIWmuH9KnDQxBszP38UbsD8Vf8-zs2Hl1JW3TnPlNMwi5H9VF8rjWOnUSqWTuEDYgeSxY_MfPxmf8bhLA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:07:16Z" level=warning msg="Too many requests, retrying after delay" after=14 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/Xrkp-wkgWTuIVBl0Hq3o-pkRyqVIDcIAd5MAAvmpcWsAURtZKf8SEuyj8uROD6s2UFgmdyf6q4h7rpVtDYTUWw==/revisions/BFCO4WIWmuH9KnDQxBszP38UbsD8Vf8-zs2Hl1JW3TnPlNMwi5H9VF8rjWOnUSqWTuEDYgeSxY_MfPxmf8bhLA==" 2024/10/30 04:08:30.513720 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/Xrkp-wkgWTuIVBl0Hq3o-pkRyqVIDcIAd5MAAvmpcWsAURtZKf8SEuyj8uROD6s2UFgmdyf6q4h7rpVtDYTUWw==/revisions/BFCO4WIWmuH9KnDQxBszP38UbsD8Vf8-zs2Hl1JW3TnPlNMwi5H9VF8rjWOnUSqWTuEDYgeSxY_MfPxmf8bhLA==: Internal server error (Code=500, Status=500), Attempt 2 2024/10/30 04:08:30.513747 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/Xrkp-wkgWTuIVBl0Hq3o-pkRyqVIDcIAd5MAAvmpcWsAURtZKf8SEuyj8uROD6s2UFgmdyf6q4h7rpVtDYTUWw==/revisions/BFCO4WIWmuH9KnDQxBszP38UbsD8Vf8-zs2Hl1JW3TnPlNMwi5H9VF8rjWOnUSqWTuEDYgeSxY_MfPxmf8bhLA==: Internal server error (Code=500, Status=500) 2024/10/30 04:08:30 ERROR : one: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/Xrkp-wkgWTuIVBl0Hq3o-pkRyqVIDcIAd5MAAvmpcWsAURtZKf8SEuyj8uROD6s2UFgmdyf6q4h7rpVtDYTUWw==/revisions/BFCO4WIWmuH9KnDQxBszP38UbsD8Vf8-zs2Hl1JW3TnPlNMwi5H9VF8rjWOnUSqWTuEDYgeSxY_MfPxmf8bhLA==: Internal server error (Code=500, Status=500) 2024/10/30 04:08:30 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa/dst': not deleting files as there were IO errors 2024/10/30 04:08:30 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa/dst': not deleting directories as there were IO errors sync_test.go:2101: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2101 Error: Received unexpected error: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/Xrkp-wkgWTuIVBl0Hq3o-pkRyqVIDcIAd5MAAvmpcWsAURtZKf8SEuyj8uROD6s2UFgmdyf6q4h7rpVtDYTUWw==/revisions/BFCO4WIWmuH9KnDQxBszP38UbsD8Vf8-zs2Hl1JW3TnPlNMwi5H9VF8rjWOnUSqWTuEDYgeSxY_MfPxmf8bhLA==: Internal server error (Code=500, Status=500) Test: TestSyncCompareDest 2024/10/30 04:08:42.076568 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/EUxwLGaOo20Y0fEd-JXLzV81-37kUtMBvAw0Iug-LhEFA1giod3URepPBzAwIphSA-SesrPN1cnIqSx5B-sbtA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:08:42Z" level=warning msg="Too many requests, retrying after delay" after=8 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/EUxwLGaOo20Y0fEd-JXLzV81-37kUtMBvAw0Iug-LhEFA1giod3URepPBzAwIphSA-SesrPN1cnIqSx5B-sbtA==/trash_multiple" 2024/10/30 04:09:00.385022 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/EUxwLGaOo20Y0fEd-JXLzV81-37kUtMBvAw0Iug-LhEFA1giod3URepPBzAwIphSA-SesrPN1cnIqSx5B-sbtA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:09:00Z" level=warning msg="Too many requests, retrying after delay" after=12 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/EUxwLGaOo20Y0fEd-JXLzV81-37kUtMBvAw0Iug-LhEFA1giod3URepPBzAwIphSA-SesrPN1cnIqSx5B-sbtA==/trash_multiple" --- FAIL: TestSyncCompareDest (327.39s) === RUN TestSyncUTFNorm run.go:180: Remote "proton drive root link ID 'rclone-test-tajuqal8kefa'", Local "Local file system at /tmp/rclone1927343786", Modify Window "1s" 2024/10/30 04:09:16 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/10/30 04:09:16 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for checks to finish 2024/10/30 04:09:16 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Waiting for transfers to finish 2024/10/30 04:09:16.748359 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2024/10/30 04:09:16.748391 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2024/10/30 04:10:18.651877 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/K5hbcD7AxsP7B5Cx1-bu4QIZp05yilY5O5UDjSSileUVjSdcgs-ogerMIqaXx8NhqXyZ8RwRVEoSKqpQMZ-Luw==/revisions/6qQTn-xOW3FiusOUD-LjuZ_7OYS-H5CP9n69QK55Na9mrg1AzxODkFSp2WLmiU3vKL8XOvptM4YsqQ4BcLmaIw==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 04:10:18.651912 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/K5hbcD7AxsP7B5Cx1-bu4QIZp05yilY5O5UDjSSileUVjSdcgs-ogerMIqaXx8NhqXyZ8RwRVEoSKqpQMZ-Luw==/revisions/6qQTn-xOW3FiusOUD-LjuZ_7OYS-H5CP9n69QK55Na9mrg1AzxODkFSp2WLmiU3vKL8XOvptM4YsqQ4BcLmaIw==: Internal server error (Code=500, Status=500) 2024/10/30 04:10:18 ERROR : Testêé: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/K5hbcD7AxsP7B5Cx1-bu4QIZp05yilY5O5UDjSSileUVjSdcgs-ogerMIqaXx8NhqXyZ8RwRVEoSKqpQMZ-Luw==/revisions/6qQTn-xOW3FiusOUD-LjuZ_7OYS-H5CP9n69QK55Na9mrg1AzxODkFSp2WLmiU3vKL8XOvptM4YsqQ4BcLmaIw==: Internal server error (Code=500, Status=500) 2024/10/30 04:10:18 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting files as there were IO errors 2024/10/30 04:10:18 ERROR : proton drive root link ID 'rclone-test-tajuqal8kefa': not deleting directories as there were IO errors sync_test.go:2551: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2551 Error: Received unexpected error: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/K5hbcD7AxsP7B5Cx1-bu4QIZp05yilY5O5UDjSSileUVjSdcgs-ogerMIqaXx8NhqXyZ8RwRVEoSKqpQMZ-Luw==/revisions/6qQTn-xOW3FiusOUD-LjuZ_7OYS-H5CP9n69QK55Na9mrg1AzxODkFSp2WLmiU3vKL8XOvptM4YsqQ4BcLmaIw==: Internal server error (Code=500, Status=500) Test: TestSyncUTFNorm 2024/10/30 04:10:30.129465 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:10:30Z" level=warning msg="Too many requests, retrying after delay" after=11 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple" 2024/10/30 04:10:52.286455 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:10:52Z" level=warning msg="Too many requests, retrying after delay" after=9 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/L4qxqpMfv1DDuSD1RdrMaWH4ttZXEL56CSbpJ2QXXneB7clrvI_Pwm2JA7bPY8OgDbQxBYBa3Lq1rgw6_TOoCA==/trash_multiple" --- FAIL: TestSyncUTFNorm (110.72s) FAIL 2024/10/30 04:11:04 DEBUG : proton drive root link ID 'rclone-test-tajuqal8kefa': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestCopyOverSelf|TestMoveOverSelf|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterChangingModtimeOnly|TestSyncCompareDest|TestSyncDoesntUpdateModtime|TestSyncIgnoreTimes|TestSyncUTFNorm|TestSyncWithUpdateOlder)$'" - Finished ERROR in 24m17.091694881s (try 4/5): exit status 1: Failed [TestCopyOverSelf TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf TestSyncIgnoreTimes TestSyncAfterChangingModtimeOnly TestSyncDoesntUpdateModtime TestSyncAfterChangingFilesSizeOnly TestSyncAfterChangingContentsOnly TestSyncWithUpdateOlder TestSyncCompareDest TestSyncUTFNorm]