"./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 5/5) 2024/10/30 04:11:04 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-kedibod9pono" 2024/10/30 04:11:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/30 04:11:04 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Has cached credentials 2024/10/30 04:11:05 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Used cached credential to initialize the ProtonDrive API 2024/10/30 04:11:06 DEBUG : Creating backend with remote "/tmp/rclone647227160" === RUN TestCopyOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:11:09 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:11:09 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2024/10/30 04:11:09 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:11:09.750714 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:11:09.750737 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:11:21.147171 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:11:21Z" 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/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==" 2024/10/30 04:11:41.461687 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:11:41Z" level=warning msg="Too many requests, retrying after delay" after=11 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==" 2024/10/30 04:12:03.311463 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T04:12:03Z" 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/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==" 2024/10/30 04:12:21.912419 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 04:12:21.912436 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:12:21 ERROR : sub dir/hello world: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==: Resource is busy. Please try again (Code=2502, Status=429) sync_test.go:611: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:611 Error: Received unexpected error: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/fgVlk5l5A-8R44v2hWvSElrjT_IWyaGOQBIb2awLbTVJhtZhUbJndxLVtLBubmbbQYoIJf97j08hSzUD2udMcg==/revisions/rcUkH5yStqpoCXZGBoAw_87TxPl1TC3w0dpGWoVkRKFKovOt3u6oOrGt73U0W7uZCWkK5UMnqUDY-xXEn8qmEg==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestCopyOverSelf --- FAIL: TestCopyOverSelf (77.22s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:12:25 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rebexim2sizi" 2024/10/30 04:12:25 DEBUG : proton drive root link ID 'rclone-test-rebexim2sizi': Has cached credentials 2024/10/30 04:12:26 DEBUG : proton drive root link ID 'rclone-test-rebexim2sizi': Used cached credential to initialize the ProtonDrive API sync_test.go:626: Server side copy (if possible) proton drive root link ID 'rclone-test-kedibod9pono' -> proton drive root link ID 'rclone-test-rebexim2sizi' 2024/10/30 04:12:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/10/30 04:12:27 DEBUG : proton drive root link ID 'rclone-test-rebexim2sizi': Waiting for checks to finish 2024/10/30 04:12:27 DEBUG : proton drive root link ID 'rclone-test-rebexim2sizi': Waiting for transfers to finish 2024/10/30 04:12:29 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/10/30 04:12:29 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:12:31.115289 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:12:31.115317 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:13:32.341690 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/WFZ37ePM5jtIU0GzqeLJegZFL7_cleW39xAZ1g-sy_mzhBekNH742xvLuWU4JA_5S6VrIfhp5TILQKTfT0nvjg==/revisions/xEj0fxUrUVfSSmTnZ6exiIZlHk6sZ_K9aasdLIiE_ju57vbovoidpx01uVhjxbKBR6dBgF1F-yIXUbNknC4mJQ==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 04:13:32.341715 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/WFZ37ePM5jtIU0GzqeLJegZFL7_cleW39xAZ1g-sy_mzhBekNH742xvLuWU4JA_5S6VrIfhp5TILQKTfT0nvjg==/revisions/xEj0fxUrUVfSSmTnZ6exiIZlHk6sZ_K9aasdLIiE_ju57vbovoidpx01uVhjxbKBR6dBgF1F-yIXUbNknC4mJQ==: 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-kedibod9pono'": 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/WFZ37ePM5jtIU0GzqeLJegZFL7_cleW39xAZ1g-sy_mzhBekNH742xvLuWU4JA_5S6VrIfhp5TILQKTfT0nvjg==/revisions/xEj0fxUrUVfSSmTnZ6exiIZlHk6sZ_K9aasdLIiE_ju57vbovoidpx01uVhjxbKBR6dBgF1F-yIXUbNknC4mJQ==: Internal server error (Code=500, Status=500) 2024/10/30 04:13:32 DEBUG : proton drive root link ID 'rclone-test-rebexim2sizi': Purge remote 2024/10/30 04:13:44.065222 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/efJTii0RbGHWI1agfpzo08SVIlfOFNbUsKGvmg_I5QVaC75MytkSiXnWskN23GrFOkVnAS5Hqyt3m2Al5jKRgg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:13:44Z" level=warning msg="Too many requests, retrying after delay" after=10 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/efJTii0RbGHWI1agfpzo08SVIlfOFNbUsKGvmg_I5QVaC75MytkSiXnWskN23GrFOkVnAS5Hqyt3m2Al5jKRgg==/trash_multiple" 2024/10/30 04:14:04.748652 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/efJTii0RbGHWI1agfpzo08SVIlfOFNbUsKGvmg_I5QVaC75MytkSiXnWskN23GrFOkVnAS5Hqyt3m2Al5jKRgg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:14:04Z" 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/efJTii0RbGHWI1agfpzo08SVIlfOFNbUsKGvmg_I5QVaC75MytkSiXnWskN23GrFOkVnAS5Hqyt3m2Al5jKRgg==/trash_multiple" --- FAIL: TestServerSideCopyOverSelf (114.67s) === RUN TestMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:14:20 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2024/10/30 04:14:20 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:14:20 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:14:20.958125 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:14:20.958157 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:14:32.628005 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:14:32Z" 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/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==" 2024/10/30 04:14:48.285291 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:14:48Z" 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/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==" 2024/10/30 04:15:11.592197 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T04:15:11Z" 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/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==" 2024/10/30 04:15:34.931548 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 04:15:34.931564 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:15:34 ERROR : sub dir/hello world: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:15:34 ERROR : sub dir/hello world: Not deleting source as copy failed: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==: Resource is busy. Please try again (Code=2502, Status=429) sync_test.go:655: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:655 Error: Received unexpected error: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/rFTYRZ8AgnqNme9-w8-t5SU3LMO6ET1L845Jwf63APS1iIxR9UvLTcduw7ACac_PSlzpivFEe2AI8DWYvKn9Pw==/revisions/tbOc6FgICH3VHLFy83_eOusCje9_rq1Bqggr-L3Q7t0njcL9wetH-d8I2J18ErnXEx4rFIeWhzAsm5-zGE2_hQ==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestMoveOverSelf --- FAIL: TestMoveOverSelf (78.53s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:15:38 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-cepemir9melu" 2024/10/30 04:15:38 DEBUG : proton drive root link ID 'rclone-test-cepemir9melu': Has cached credentials 2024/10/30 04:15:39 DEBUG : proton drive root link ID 'rclone-test-cepemir9melu': Used cached credential to initialize the ProtonDrive API sync_test.go:671: Server side copy (if possible) proton drive root link ID 'rclone-test-kedibod9pono' -> proton drive root link ID 'rclone-test-cepemir9melu' 2024/10/30 04:15:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/10/30 04:15:40 DEBUG : proton drive root link ID 'rclone-test-cepemir9melu': Waiting for checks to finish 2024/10/30 04:15:40 DEBUG : proton drive root link ID 'rclone-test-cepemir9melu': Waiting for transfers to finish 2024/10/30 04:15:43 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/10/30 04:15:43 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:15:44.415377 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:15:44.415403 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:16:45.521051 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/8uW9jSvkdTW6_7sKUqS1YNI3nq3OOx1ClSkBtbVvEgkByZK2e2A4fpBBQNCQzCYp0klUhgc8jcEc2oB1MNomtQ==/revisions/kS3iwWWoLv8t4z2Ty5ETOnzuhGtK7-xopNpBAiIuIdodUSzCDG0ytX-VBdcizzsQvtwZgaKxjKnf7LsBDB8Cng==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 04:16:45.521070 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/8uW9jSvkdTW6_7sKUqS1YNI3nq3OOx1ClSkBtbVvEgkByZK2e2A4fpBBQNCQzCYp0klUhgc8jcEc2oB1MNomtQ==/revisions/kS3iwWWoLv8t4z2Ty5ETOnzuhGtK7-xopNpBAiIuIdodUSzCDG0ytX-VBdcizzsQvtwZgaKxjKnf7LsBDB8Cng==: 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-kedibod9pono'": 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/8uW9jSvkdTW6_7sKUqS1YNI3nq3OOx1ClSkBtbVvEgkByZK2e2A4fpBBQNCQzCYp0klUhgc8jcEc2oB1MNomtQ==/revisions/kS3iwWWoLv8t4z2Ty5ETOnzuhGtK7-xopNpBAiIuIdodUSzCDG0ytX-VBdcizzsQvtwZgaKxjKnf7LsBDB8Cng==: Internal server error (Code=500, Status=500) 2024/10/30 04:16:45 DEBUG : proton drive root link ID 'rclone-test-cepemir9melu': Purge remote 2024/10/30 04:16:57.502418 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/f6_aJrT0JA961tS87kRCQ6yXXXnXWNac_EayGtuGfEWUXcmVTr1oQDjfFzigff9p9Fqr73-qlRxVxCMXC6DEMg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:16:57Z" 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/f6_aJrT0JA961tS87kRCQ6yXXXnXWNac_EayGtuGfEWUXcmVTr1oQDjfFzigff9p9Fqr73-qlRxVxCMXC6DEMg==/trash_multiple" 2024/10/30 04:17:14.480816 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/f6_aJrT0JA961tS87kRCQ6yXXXnXWNac_EayGtuGfEWUXcmVTr1oQDjfFzigff9p9Fqr73-qlRxVxCMXC6DEMg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:17:14Z" 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/f6_aJrT0JA961tS87kRCQ6yXXXnXWNac_EayGtuGfEWUXcmVTr1oQDjfFzigff9p9Fqr73-qlRxVxCMXC6DEMg==/trash_multiple" --- FAIL: TestServerSideMoveOverSelf (108.99s) === RUN TestSyncIgnoreTimes run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:17:27 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/10/30 04:17:27 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:17:27 DEBUG : existing: Unchanged skipping 2024/10/30 04:17:27 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:17:27 DEBUG : Waiting for deletions to finish 2024/10/30 04:17:27 INFO : There was nothing to transfer run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:17:27 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/10/30 04:17:27 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:17:27 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:17:27.889805 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:17:27.889828 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:18:29.199718 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/ThU7lvdkOxX5Bbq4q9OvxBj9x2D9pzHpX1Qh7EyPYyCj-scWaMxEGzGocEtKW6WvSUn7STnvjnpfzL8rqejXSw==/revisions/40RXy2_rMVV7nOcvXemYxbCfhbJOKc9MPDfyk4Evb1gewtYcXcbEk7h_WCvbWSC5iygKiX8wgyYL_NLxCzNmuA==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 04:18:29.199742 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/ThU7lvdkOxX5Bbq4q9OvxBj9x2D9pzHpX1Qh7EyPYyCj-scWaMxEGzGocEtKW6WvSUn7STnvjnpfzL8rqejXSw==/revisions/40RXy2_rMVV7nOcvXemYxbCfhbJOKc9MPDfyk4Evb1gewtYcXcbEk7h_WCvbWSC5iygKiX8wgyYL_NLxCzNmuA==: Internal server error (Code=500, Status=500) 2024/10/30 04:18:29 ERROR : existing: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/ThU7lvdkOxX5Bbq4q9OvxBj9x2D9pzHpX1Qh7EyPYyCj-scWaMxEGzGocEtKW6WvSUn7STnvjnpfzL8rqejXSw==/revisions/40RXy2_rMVV7nOcvXemYxbCfhbJOKc9MPDfyk4Evb1gewtYcXcbEk7h_WCvbWSC5iygKiX8wgyYL_NLxCzNmuA==: Internal server error (Code=500, Status=500) 2024/10/30 04:18:29 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': not deleting files as there were IO errors 2024/10/30 04:18:29 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': 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: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/ThU7lvdkOxX5Bbq4q9OvxBj9x2D9pzHpX1Qh7EyPYyCj-scWaMxEGzGocEtKW6WvSUn7STnvjnpfzL8rqejXSw==/revisions/40RXy2_rMVV7nOcvXemYxbCfhbJOKc9MPDfyk4Evb1gewtYcXcbEk7h_WCvbWSC5iygKiX8wgyYL_NLxCzNmuA==: Internal server error (Code=500, Status=500) Test: TestSyncIgnoreTimes 2024/10/30 04:18:39.965637 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:18:39Z" level=warning msg="Too many requests, retrying after delay" after=5 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" 2024/10/30 04:18:55.554988 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:18:55Z" level=warning msg="Too many requests, retrying after delay" after=5 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" --- FAIL: TestSyncIgnoreTimes (103.67s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:19:10 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:19:10 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 04:19:10 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/10/30 04:19:10 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2024/10/30 04:19:10 DEBUG : empty space: Unchanged skipping 2024/10/30 04:19:10 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:19:10 DEBUG : Waiting for deletions to finish 2024/10/30 04:19:10 INFO : There was nothing to transfer run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:19:11 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:19:11 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 04:19:11 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/10/30 04:19:11 NOTICE: proton drive root link ID 'rclone-test-kedibod9pono': Forced to upload files to set modification times on this backend. 2024/10/30 04:19:11 INFO : empty space: src and dst identical but can't set mod time without re-uploading 2024/10/30 04:19:11 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:19:11.869040 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:19:11.869061 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:20:13.134082 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/HGJrRjgPQgUU5GBWJqvEkIEDf1MhGBVLI-4MTAT_ldUYTAv7OhgRQ5ukdtdwyl4rCCmM9f0irDHI4Y4j1moXFw==/revisions/6-35RjpGJnOH9TuL7Cp3uNvSuezickjhGSII5rGBNFfHjbf9sT7ik6AUQvYM2-9R7tPObSQ-JtNzGHpM-5KcQA==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 04:20:13.134117 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/HGJrRjgPQgUU5GBWJqvEkIEDf1MhGBVLI-4MTAT_ldUYTAv7OhgRQ5ukdtdwyl4rCCmM9f0irDHI4Y4j1moXFw==/revisions/6-35RjpGJnOH9TuL7Cp3uNvSuezickjhGSII5rGBNFfHjbf9sT7ik6AUQvYM2-9R7tPObSQ-JtNzGHpM-5KcQA==: Internal server error (Code=500, Status=500) 2024/10/30 04:20:13 ERROR : empty space: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/HGJrRjgPQgUU5GBWJqvEkIEDf1MhGBVLI-4MTAT_ldUYTAv7OhgRQ5ukdtdwyl4rCCmM9f0irDHI4Y4j1moXFw==/revisions/6-35RjpGJnOH9TuL7Cp3uNvSuezickjhGSII5rGBNFfHjbf9sT7ik6AUQvYM2-9R7tPObSQ-JtNzGHpM-5KcQA==: Internal server error (Code=500, Status=500) 2024/10/30 04:20:13 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': not deleting files as there were IO errors 2024/10/30 04:20:13 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': 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/HGJrRjgPQgUU5GBWJqvEkIEDf1MhGBVLI-4MTAT_ldUYTAv7OhgRQ5ukdtdwyl4rCCmM9f0irDHI4Y4j1moXFw==/revisions/6-35RjpGJnOH9TuL7Cp3uNvSuezickjhGSII5rGBNFfHjbf9sT7ik6AUQvYM2-9R7tPObSQ-JtNzGHpM-5KcQA==: Internal server error (Code=500, Status=500) Test: TestSyncAfterChangingModtimeOnly 2024/10/30 04:20:24.070710 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:20:24Z" 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/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" 2024/10/30 04:20:48.720140 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:20:48Z" level=warning msg="Too many requests, retrying after delay" after=10 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" --- FAIL: TestSyncAfterChangingModtimeOnly (110.46s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:21:01 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 04:21:01 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:21:01 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone647227160) 2024/10/30 04:21:01 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (proton drive root link ID 'rclone-test-kedibod9pono') 2024/10/30 04:21:01 DEBUG : foo: sha1 differ 2024/10/30 04:21:01 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:21:01.738590 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:21:01.738616 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:21:13.197243 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:21:13Z" 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/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==" 2024/10/30 04:21:32.531656 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:21:32Z" 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/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==" 2024/10/30 04:21:55.231055 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T04:21:55Z" 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/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==" 2024/10/30 04:22:19.009729 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 04:22:19.009749 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:22:19 ERROR : foo: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:22:19 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': not deleting files as there were IO errors 2024/10/30 04:22:19 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': 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/xpMGbmF9JrvQCKlaUIWyAP1z6epac7qbNLYDZvaCzHMSrGAGRuD2NSIkxawSmu7287POdLTYM4yXTmJ-7EVU4Q==/revisions/U653-smmWShh1ihdDKwgeYfRwF9zmwpRZNQQH0KIGrQBMaoKIt8YRWeXVqyYFyUZkZWJB6bzDPSN_pUL41qlUA==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestSyncDoesntUpdateModtime --- FAIL: TestSyncDoesntUpdateModtime (80.27s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:22:21 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/10/30 04:22:21 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:22:21 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:22:21.919196 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:22:21.919226 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:22:33.045735 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/C98hriYhy9LKcXtl38_1Z-4_qlt2BPgX6FtF5WereoHEn4BdiFzsYRcS6lmxU38UK6l7N_lnpnFWS0lUsRyY1A==/revisions/HSsPg7OuXqVXIJLQ0xJ896Q9ZliCkS2myhep4Cv1HlAIV8mD7ibZYfa7dQ8jBR1Oka3z0pRvuBsC9OcBDBrxRg==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:22:33Z" 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/C98hriYhy9LKcXtl38_1Z-4_qlt2BPgX6FtF5WereoHEn4BdiFzsYRcS6lmxU38UK6l7N_lnpnFWS0lUsRyY1A==/revisions/HSsPg7OuXqVXIJLQ0xJ896Q9ZliCkS2myhep4Cv1HlAIV8mD7ibZYfa7dQ8jBR1Oka3z0pRvuBsC9OcBDBrxRg==" 2024/10/30 04:23:42.270724 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/C98hriYhy9LKcXtl38_1Z-4_qlt2BPgX6FtF5WereoHEn4BdiFzsYRcS6lmxU38UK6l7N_lnpnFWS0lUsRyY1A==/revisions/HSsPg7OuXqVXIJLQ0xJ896Q9ZliCkS2myhep4Cv1HlAIV8mD7ibZYfa7dQ8jBR1Oka3z0pRvuBsC9OcBDBrxRg==: Internal server error (Code=500, Status=500), Attempt 2 2024/10/30 04:23:42.270748 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/C98hriYhy9LKcXtl38_1Z-4_qlt2BPgX6FtF5WereoHEn4BdiFzsYRcS6lmxU38UK6l7N_lnpnFWS0lUsRyY1A==/revisions/HSsPg7OuXqVXIJLQ0xJ896Q9ZliCkS2myhep4Cv1HlAIV8mD7ibZYfa7dQ8jBR1Oka3z0pRvuBsC9OcBDBrxRg==: Internal server error (Code=500, Status=500) 2024/10/30 04:23:42 ERROR : potato: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/C98hriYhy9LKcXtl38_1Z-4_qlt2BPgX6FtF5WereoHEn4BdiFzsYRcS6lmxU38UK6l7N_lnpnFWS0lUsRyY1A==/revisions/HSsPg7OuXqVXIJLQ0xJ896Q9ZliCkS2myhep4Cv1HlAIV8mD7ibZYfa7dQ8jBR1Oka3z0pRvuBsC9OcBDBrxRg==: Internal server error (Code=500, Status=500) 2024/10/30 04:23:42 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': not deleting files as there were IO errors 2024/10/30 04:23:42 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': 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/C98hriYhy9LKcXtl38_1Z-4_qlt2BPgX6FtF5WereoHEn4BdiFzsYRcS6lmxU38UK6l7N_lnpnFWS0lUsRyY1A==/revisions/HSsPg7OuXqVXIJLQ0xJ896Q9ZliCkS2myhep4Cv1HlAIV8mD7ibZYfa7dQ8jBR1Oka3z0pRvuBsC9OcBDBrxRg==: Internal server error (Code=500, Status=500) Test: TestSyncAfterChangingFilesSizeOnly 2024/10/30 04:23:53.073444 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:23:53Z" 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/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" 2024/10/30 04:24:17.722014 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:24:17Z" level=warning msg="Too many requests, retrying after delay" after=5 method=POST pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" --- FAIL: TestSyncAfterChangingFilesSizeOnly (123.25s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:24:24 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:24:24 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:24:24 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone647227160) 2024/10/30 04:24:24 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (proton drive root link ID 'rclone-test-kedibod9pono') 2024/10/30 04:24:24 DEBUG : potato: sha1 differ 2024/10/30 04:24:24 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:24:24.995594 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:24:24.995659 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:24:36.389304 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:24:36Z" 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/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==" 2024/10/30 04:24:59.903737 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:24:59Z" 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/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==" 2024/10/30 04:25:17.161038 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T04:25:17Z" level=warning msg="Too many requests, retrying after delay" after=11 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==" 2024/10/30 04:25:38.770565 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 04:25:38.770590 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:25:38 ERROR : potato: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:25:38 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': not deleting files as there were IO errors 2024/10/30 04:25:38 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': 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: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/M3QKRwdf8Wo_GCDNOqLipvX5IohMewaaCbagc_v-NhZyGpn_BqEQXkU5AIgCBw5F8fymSR3N1iKtLnma34HT6Q==/revisions/pl_yYypl-BPZ6y3mwz27ST6V1FzW_dw0CFRgEFZGXJeVgRv2HxTENKgn9fbNTrU_v7gEfjw3-evoDM2sG61X1A==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestSyncAfterChangingContentsOnly --- FAIL: TestSyncAfterChangingContentsOnly (76.42s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:25:44 DEBUG : five: Need to transfer - File not found at Destination 2024/10/30 04:25:44 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/10/30 04:25:44 DEBUG : one: Destination is newer than source, skipping 2024/10/30 04:25:44 DEBUG : three: Sizes identical 2024/10/30 04:25:44 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:25:44 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/10/30 04:25:44 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/10/30 04:25:44 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone647227160) 2024/10/30 04:25:44 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (proton drive root link ID 'rclone-test-kedibod9pono') 2024/10/30 04:25:44 DEBUG : two: sha1 differ 2024/10/30 04:25:44 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:25:44.753970 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:25:44.753991 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:25:44.767787 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:25:44.767829 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:25:45 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/10/30 04:25:45 INFO : five: Copied (new) 2024/10/30 04:25:55.945053 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:25:55Z" 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/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==" 2024/10/30 04:25:56.774423 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/NjgHMsyXxxrRRzXwdnf3FuZKzDgokVb_uJLn2_Cwh8r95kHFn78dP9t5dcduQXC-uQsFIhZk7uHMuN4CGvVCnw==/revisions/spC3WLuvoLZ-e66qTpSb8wxj4UJC1FveMwhpOYedaOp07lmhRh8qA1NUpYJRMhkl8gtU0mMerwIwBbypCkwo7Q==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:25:56Z" 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/NjgHMsyXxxrRRzXwdnf3FuZKzDgokVb_uJLn2_Cwh8r95kHFn78dP9t5dcduQXC-uQsFIhZk7uHMuN4CGvVCnw==/revisions/spC3WLuvoLZ-e66qTpSb8wxj4UJC1FveMwhpOYedaOp07lmhRh8qA1NUpYJRMhkl8gtU0mMerwIwBbypCkwo7Q==" 2024/10/30 04:26:15.394450 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:26:15Z" level=warning msg="Too many requests, retrying after delay" after=11 method=PUT pkg=go-proton-api status=429 url="https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==" 2024/10/30 04:26:37.233608 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T04:26:37Z" 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/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==" 2024/10/30 04:27:01.837339 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 04:27:01.837364 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:27:01 ERROR : four: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/-hMdJSwlV7JiSdx9xDDy5BkxRQDZ1-NSGXN1WhZx-_UHRRZpQX6ByvX1MIEAV30K3b39Y5pLm33HziowdAB0jg==/revisions/l4y8xExLZMUisy6QSGI5SM-0GD6QQ-IGpx2TwTPB1hS6tO5kLRG6TpNljzBh23RyWRMNJSIteK5HKy1UWa1GsQ==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:27:01.974764 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/NjgHMsyXxxrRRzXwdnf3FuZKzDgokVb_uJLn2_Cwh8r95kHFn78dP9t5dcduQXC-uQsFIhZk7uHMuN4CGvVCnw==/revisions/spC3WLuvoLZ-e66qTpSb8wxj4UJC1FveMwhpOYedaOp07lmhRh8qA1NUpYJRMhkl8gtU0mMerwIwBbypCkwo7Q==: Internal server error (Code=500, Status=500), Attempt 2 2024/10/30 04:27:01.974784 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/NjgHMsyXxxrRRzXwdnf3FuZKzDgokVb_uJLn2_Cwh8r95kHFn78dP9t5dcduQXC-uQsFIhZk7uHMuN4CGvVCnw==/revisions/spC3WLuvoLZ-e66qTpSb8wxj4UJC1FveMwhpOYedaOp07lmhRh8qA1NUpYJRMhkl8gtU0mMerwIwBbypCkwo7Q==: Internal server error (Code=500, Status=500) 2024/10/30 04:27:01 ERROR : two: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/NjgHMsyXxxrRRzXwdnf3FuZKzDgokVb_uJLn2_Cwh8r95kHFn78dP9t5dcduQXC-uQsFIhZk7uHMuN4CGvVCnw==/revisions/spC3WLuvoLZ-e66qTpSb8wxj4UJC1FveMwhpOYedaOp07lmhRh8qA1NUpYJRMhkl8gtU0mMerwIwBbypCkwo7Q==: Internal server error (Code=500, Status=500) 2024/10/30 04:27:01 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': not deleting files as there were IO errors 2024/10/30 04:27:01 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': 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: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/NjgHMsyXxxrRRzXwdnf3FuZKzDgokVb_uJLn2_Cwh8r95kHFn78dP9t5dcduQXC-uQsFIhZk7uHMuN4CGvVCnw==/revisions/spC3WLuvoLZ-e66qTpSb8wxj4UJC1FveMwhpOYedaOp07lmhRh8qA1NUpYJRMhkl8gtU0mMerwIwBbypCkwo7Q==: Internal server error (Code=500, Status=500) Test: TestSyncWithUpdateOlder 2024/10/30 04:27:14.747708 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:27:14Z" 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/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" 2024/10/30 04:27:38.091815 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:27:38Z" 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/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" --- FAIL: TestSyncWithUpdateOlder (131.82s) === RUN TestSyncCompareDest run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:27:51 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-kedibod9pono/dst" 2024/10/30 04:27:51 DEBUG : Config file has changed externally - reloading 2024/10/30 04:27:51 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono/dst': Has cached credentials 2024/10/30 04:27:52 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono/dst': Used cached credential to initialize the ProtonDrive API 2024/10/30 04:29:24 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-kedibod9pono/CompareDest" 2024/10/30 04:29:24 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono/CompareDest': Has cached credentials 2024/10/30 04:29:25 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono/CompareDest': Used cached credential to initialize the ProtonDrive API 2024/10/30 04:30:57 DEBUG : one: Need to transfer - File not found at Destination 2024/10/30 04:30:57 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono/dst': Waiting for checks to finish 2024/10/30 04:30:57 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono/dst': Waiting for transfers to finish 2024/10/30 04:30:58 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/10/30 04:30:58 INFO : one: Copied (new) 2024/10/30 04:30:58 DEBUG : Waiting for deletions to finish 2024/10/30 04:31:00 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono/dst': Waiting for checks to finish 2024/10/30 04:31:00 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/10/30 04:31:00 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono/dst': Waiting for transfers to finish 2024/10/30 04:31:01.228709 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:31:01.228730 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:31:13.237679 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:31:13Z" 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/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==" 2024/10/30 04:31:37.628264 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:31:37Z" 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/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==" 2024/10/30 04:31:58.520896 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T04:31:58Z" 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/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==" 2024/10/30 04:32:17.055458 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 04:32:17.055478 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:32:17 ERROR : one: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 04:32:17 ERROR : proton drive root link ID 'rclone-test-kedibod9pono/dst': not deleting files as there were IO errors 2024/10/30 04:32:17 ERROR : proton drive root link ID 'rclone-test-kedibod9pono/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: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/A1DVJyrQ7Fp46NgnXXQye06wa7fbSc4QRl1A5irzY4byz2C3b3YmxROji0bDIIqvlJBrR8JgX6SNo_phMXskmg==/revisions/dhlYuLnSP0_YZ-QjYztQtIflo3bWKa0F2XBpdVkn_TDrW9OB5Zauo0aR3vy-CMFBLs_ihFR1F91x217mkbGWCw==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestSyncCompareDest --- FAIL: TestSyncCompareDest (267.15s) === RUN TestSyncUTFNorm run.go:180: Remote "proton drive root link ID 'rclone-test-kedibod9pono'", Local "Local file system at /tmp/rclone647227160", Modify Window "1s" 2024/10/30 04:32:20 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/10/30 04:32:20 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for checks to finish 2024/10/30 04:32:20 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': Waiting for transfers to finish 2024/10/30 04:32:20.544810 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:32:20.544834 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:33:21.797525 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/s5kQEqURc2WdGbJDxUPMuG5rOzOCOCPjQDocFvFUnLb3EXnihqm872Jt1d4kfa8qcmtxbUCfUD_vKn92QS0rkg==/revisions/AMJM0zAqc2fNLETDVPzm9fmnpqA5VAfnnJMwVnW9jZ1INSPDxxpo1jki_BGXD_L-BCBxbqbynZ8YnGt9fnDu8w==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 04:33:21.797553 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/s5kQEqURc2WdGbJDxUPMuG5rOzOCOCPjQDocFvFUnLb3EXnihqm872Jt1d4kfa8qcmtxbUCfUD_vKn92QS0rkg==/revisions/AMJM0zAqc2fNLETDVPzm9fmnpqA5VAfnnJMwVnW9jZ1INSPDxxpo1jki_BGXD_L-BCBxbqbynZ8YnGt9fnDu8w==: Internal server error (Code=500, Status=500) 2024/10/30 04:33:21 ERROR : Testêé: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/s5kQEqURc2WdGbJDxUPMuG5rOzOCOCPjQDocFvFUnLb3EXnihqm872Jt1d4kfa8qcmtxbUCfUD_vKn92QS0rkg==/revisions/AMJM0zAqc2fNLETDVPzm9fmnpqA5VAfnnJMwVnW9jZ1INSPDxxpo1jki_BGXD_L-BCBxbqbynZ8YnGt9fnDu8w==: Internal server error (Code=500, Status=500) 2024/10/30 04:33:21 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': not deleting files as there were IO errors 2024/10/30 04:33:21 ERROR : proton drive root link ID 'rclone-test-kedibod9pono': 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/s5kQEqURc2WdGbJDxUPMuG5rOzOCOCPjQDocFvFUnLb3EXnihqm872Jt1d4kfa8qcmtxbUCfUD_vKn92QS0rkg==/revisions/AMJM0zAqc2fNLETDVPzm9fmnpqA5VAfnnJMwVnW9jZ1INSPDxxpo1jki_BGXD_L-BCBxbqbynZ8YnGt9fnDu8w==: Internal server error (Code=500, Status=500) Test: TestSyncUTFNorm 2024/10/30 04:33:33.234617 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T04:33:33Z" 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/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" 2024/10/30 04:33:54.688494 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T04:33:54Z" 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/JHW-ldlr_NA4MwlFlVVIDqrWwe_FdHKK5RTIVarzcdi5U6fI5pn6aUvnOb3W-412g3pMNHGmt7dHD1QhYijcLA==/trash_multiple" --- FAIL: TestSyncUTFNorm (105.55s) FAIL 2024/10/30 04:34:04 DEBUG : proton drive root link ID 'rclone-test-kedibod9pono': 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 22m59.669919624s (try 5/5): exit status 1: Failed [TestCopyOverSelf TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf TestSyncIgnoreTimes TestSyncAfterChangingModtimeOnly TestSyncDoesntUpdateModtime TestSyncAfterChangingFilesSizeOnly TestSyncAfterChangingContentsOnly TestSyncWithUpdateOlder TestSyncCompareDest TestSyncUTFNorm]