"./operations.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest|TestParseSumFile)$'" - Starting (try 2/5) 2024/10/30 01:35:13 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lemedud8toli" 2024/10/30 01:35:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/30 01:35:13 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli': Has cached credentials 2024/10/30 01:35:14 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli': Used cached credential to initialize the ProtonDrive API 2024/10/30 01:35:15 DEBUG : Creating backend with remote "/tmp/rclone13089700" === RUN TestParseSumFile run.go:180: Remote "proton drive root link ID 'rclone-test-lemedud8toli'", Local "Local file system at /tmp/rclone13089700", Modify Window "1s" 2024/10/30 01:35:19 NOTICE: test.sum: improperly formatted checksum line 4 2024/10/30 01:35:19 NOTICE: test.sum: improperly formatted checksum line 5 2024/10/30 01:35:19 NOTICE: test.sum: improperly formatted checksum line 6 2024/10/30 01:35:19 NOTICE: test.sum: 2 warning(s) suppressed... 2024/10/30 01:35:20.195682 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 01:35:20.195709 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 01:36:22.092473 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/5EWHaFsu5xIo6mq_aE1FM04AC4FSQFH2ANnUnRfYd2jW2EbxM2HNmsCAPgfzqkrIok-QrpJb4B9q_hZtbkXz9w==/revisions/fpgbfgv8AgiqcDpf2l4j6fOe4GzDZq9JmbykEDkKsa2lPq8j89SbYRdTJFK-GXxYSTqQxWMq_zK6P32wSN8deg==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 01:36:22.092496 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/5EWHaFsu5xIo6mq_aE1FM04AC4FSQFH2ANnUnRfYd2jW2EbxM2HNmsCAPgfzqkrIok-QrpJb4B9q_hZtbkXz9w==/revisions/fpgbfgv8AgiqcDpf2l4j6fOe4GzDZq9JmbykEDkKsa2lPq8j89SbYRdTJFK-GXxYSTqQxWMq_zK6P32wSN8deg==: Internal server error (Code=500, Status=500) run.go:285: Failed to put "test.sum" to "proton drive root link ID 'rclone-test-lemedud8toli'": 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/5EWHaFsu5xIo6mq_aE1FM04AC4FSQFH2ANnUnRfYd2jW2EbxM2HNmsCAPgfzqkrIok-QrpJb4B9q_hZtbkXz9w==/revisions/fpgbfgv8AgiqcDpf2l4j6fOe4GzDZq9JmbykEDkKsa2lPq8j89SbYRdTJFK-GXxYSTqQxWMq_zK6P32wSN8deg==: Internal server error (Code=500, Status=500) 2024/10/30 01:36:33.911658 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/I1sKaw_V2tcr5fFL4vtSh1nD-TYMivnpWN91zpx8cjVM1iwN3v6VUr7tkbO5fJ2jOHcvPUQqfYSVx_5G80i4Bg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T01:36:33Z" 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/I1sKaw_V2tcr5fFL4vtSh1nD-TYMivnpWN91zpx8cjVM1iwN3v6VUr7tkbO5fJ2jOHcvPUQqfYSVx_5G80i4Bg==/trash_multiple" 2024/10/30 01:36:57.461813 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/I1sKaw_V2tcr5fFL4vtSh1nD-TYMivnpWN91zpx8cjVM1iwN3v6VUr7tkbO5fJ2jOHcvPUQqfYSVx_5G80i4Bg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T01:36:57Z" 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/I1sKaw_V2tcr5fFL4vtSh1nD-TYMivnpWN91zpx8cjVM1iwN3v6VUr7tkbO5fJ2jOHcvPUQqfYSVx_5G80i4Bg==/trash_multiple" --- FAIL: TestParseSumFile (111.54s) === RUN TestCheckSumDownload run.go:180: Remote "proton drive root link ID 'rclone-test-lemedud8toli'", Local "Local file system at /tmp/rclone13089700", Modify Window "1s" 2024/10/30 01:37:07 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lemedud8toli/data" 2024/10/30 01:37:07 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli/data': Has cached credentials 2024/10/30 01:37:08 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli/data': Used cached credential to initialize the ProtonDrive API === RUN TestCheckSumDownload/subtest1 2024/10/30 01:38:50.576095 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 01:38:50.576126 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 01:39:52.503902 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/j_k4LqsNWI5ZrrzmkK7Dg3t09rmCVAEJREV_Mn7FbXFgA_2cN_2WZbZFy9IzfhXBhWYD3SFsdCzJzm7Tbxah6A==/revisions/JWT3AgGCowMNbVsB2N8rESpX83jRFd7IUl7h9VLnVPqC5vI1K2ZIvG-kERpanjM75poBBszBU_-3XE0JeaJNaA==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 01:39:52.503927 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/j_k4LqsNWI5ZrrzmkK7Dg3t09rmCVAEJREV_Mn7FbXFgA_2cN_2WZbZFy9IzfhXBhWYD3SFsdCzJzm7Tbxah6A==/revisions/JWT3AgGCowMNbVsB2N8rESpX83jRFd7IUl7h9VLnVPqC5vI1K2ZIvG-kERpanjM75poBBszBU_-3XE0JeaJNaA==: Internal server error (Code=500, Status=500) === NAME TestCheckSumDownload run.go:285: Failed to put "test.sum" to "proton drive root link ID 'rclone-test-lemedud8toli'": 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/j_k4LqsNWI5ZrrzmkK7Dg3t09rmCVAEJREV_Mn7FbXFgA_2cN_2WZbZFy9IzfhXBhWYD3SFsdCzJzm7Tbxah6A==/revisions/JWT3AgGCowMNbVsB2N8rESpX83jRFd7IUl7h9VLnVPqC5vI1K2ZIvG-kERpanjM75poBBszBU_-3XE0JeaJNaA==: Internal server error (Code=500, Status=500) 2024/10/30 01:40:03.576290 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/I1sKaw_V2tcr5fFL4vtSh1nD-TYMivnpWN91zpx8cjVM1iwN3v6VUr7tkbO5fJ2jOHcvPUQqfYSVx_5G80i4Bg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T01:40:03Z" 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/I1sKaw_V2tcr5fFL4vtSh1nD-TYMivnpWN91zpx8cjVM1iwN3v6VUr7tkbO5fJ2jOHcvPUQqfYSVx_5G80i4Bg==/trash_multiple" 2024/10/30 01:40:22.105619 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/I1sKaw_V2tcr5fFL4vtSh1nD-TYMivnpWN91zpx8cjVM1iwN3v6VUr7tkbO5fJ2jOHcvPUQqfYSVx_5G80i4Bg==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T01:40:22Z" 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/I1sKaw_V2tcr5fFL4vtSh1nD-TYMivnpWN91zpx8cjVM1iwN3v6VUr7tkbO5fJ2jOHcvPUQqfYSVx_5G80i4Bg==/trash_multiple" --- FAIL: TestCheckSumDownload (210.66s) --- PASS: TestCheckSumDownload/subtest1 (3.17s) === RUN TestCopyFileCompareDest run.go:180: Remote "proton drive root link ID 'rclone-test-lemedud8toli'", Local "Local file system at /tmp/rclone13089700", Modify Window "1s" 2024/10/30 01:40:37 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lemedud8toli/dst" 2024/10/30 01:40:37 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli/dst': Has cached credentials 2024/10/30 01:40:38 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli/dst': Used cached credential to initialize the ProtonDrive API 2024/10/30 01:42:10 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lemedud8toli/CompareDest" 2024/10/30 01:42:10 DEBUG : Config file has changed externally - reloading 2024/10/30 01:42:10 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli/CompareDest': Has cached credentials 2024/10/30 01:42:12 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli/CompareDest': Used cached credential to initialize the ProtonDrive API 2024/10/30 01:43:43 DEBUG : one: Need to transfer - File not found at Destination 2024/10/30 01:43:46 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/10/30 01:43:46 INFO : one: Copied (new) 2024/10/30 01:43:49 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/10/30 01:43:49.490776 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 01:43:49.490819 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 01:44:01.762235 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T01:44:01Z" 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/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==" 2024/10/30 01:44:19.745442 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T01:44:19Z" 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/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==" 2024/10/30 01:44:36.377432 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 3 time="2024-10-30T01:44:36Z" 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/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==" 2024/10/30 01:44:55.886877 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 4 2024/10/30 01:44:55.886895 ERROR RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==: Resource is busy. Please try again (Code=2502, Status=429) 2024/10/30 01:44:55 ERROR : one: Failed to copy: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==: Resource is busy. Please try again (Code=2502, Status=429) copy_test.go:219: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:219 Error: Received unexpected error: 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/1XvSpt2HU6NGBMVcSBK-7Mk_sC95ivhJkl1T8U323jOUTPUOJ0EyW2W_Z82dqJy6kMxC7hMi4Eg849bmWLPd2A==/revisions/4ItPlqXehumzExiTyXTyZ-6yYmucw9rIXquOWRa4ITw6C0lJsNd5yeje-4Z_uQG5NeMBPX0i9N3AKEFd1ZLVGA==: Resource is busy. Please try again (Code=2502, Status=429) Test: TestCopyFileCompareDest --- FAIL: TestCopyFileCompareDest (260.40s) FAIL 2024/10/30 01:44:58 DEBUG : proton drive root link ID 'rclone-test-lemedud8toli': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest|TestParseSumFile)$'" - Finished ERROR in 9m45.080418127s (try 2/5): exit status 1: Failed [TestParseSumFile TestCheckSumDownload TestCopyFileCompareDest]