"./operations.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest|TestParseSumFile)$'" - Starting (try 3/5) 2024/10/30 01:44:58 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-refunod5cipi" 2024/10/30 01:44:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/30 01:44:58 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi': Has cached credentials 2024/10/30 01:45:00 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi': Used cached credential to initialize the ProtonDrive API 2024/10/30 01:45:00 DEBUG : Creating backend with remote "/tmp/rclone1475793424" === RUN TestParseSumFile run.go:180: Remote "proton drive root link ID 'rclone-test-refunod5cipi'", Local "Local file system at /tmp/rclone1475793424", Modify Window "1s" 2024/10/30 01:45:04 NOTICE: test.sum: improperly formatted checksum line 4 2024/10/30 01:45:04 NOTICE: test.sum: improperly formatted checksum line 5 2024/10/30 01:45:04 NOTICE: test.sum: improperly formatted checksum line 6 2024/10/30 01:45:04 NOTICE: test.sum: 2 warning(s) suppressed... 2024/10/30 01:45:05.755933 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:45:05.755957 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:46:07.684151 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/7BFxUNz7ThuBFMrNBrrNVvZ4zVqpP_9iMpN2j_7AVnQ4Bsvu9XxC86syvLRzPCApdw0DWD6fXtCUjotUpZqkHA==/revisions/3tKwsufK0SP1ZBwzXb_ej0DAtfEsTsG09wGugH6po0023JvhdpHfG_AJVE25OGIz-d0NOvpqVwPE5ZTUheirNg==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 01:46:07.684171 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/7BFxUNz7ThuBFMrNBrrNVvZ4zVqpP_9iMpN2j_7AVnQ4Bsvu9XxC86syvLRzPCApdw0DWD6fXtCUjotUpZqkHA==/revisions/3tKwsufK0SP1ZBwzXb_ej0DAtfEsTsG09wGugH6po0023JvhdpHfG_AJVE25OGIz-d0NOvpqVwPE5ZTUheirNg==: Internal server error (Code=500, Status=500) run.go:285: Failed to put "test.sum" to "proton drive root link ID 'rclone-test-refunod5cipi'": 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/7BFxUNz7ThuBFMrNBrrNVvZ4zVqpP_9iMpN2j_7AVnQ4Bsvu9XxC86syvLRzPCApdw0DWD6fXtCUjotUpZqkHA==/revisions/3tKwsufK0SP1ZBwzXb_ej0DAtfEsTsG09wGugH6po0023JvhdpHfG_AJVE25OGIz-d0NOvpqVwPE5ZTUheirNg==: Internal server error (Code=500, Status=500) 2024/10/30 01:46:19.714144 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/Rx_6M0WyJ7RLVNoKHLVWHrt-OTtZkjS1sLgmvtUHhCrb_wzjKVLysLRELkM5QwpDGPghHM4_xIYpxyz6ZtTJvQ==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T01:46:19Z" 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/Rx_6M0WyJ7RLVNoKHLVWHrt-OTtZkjS1sLgmvtUHhCrb_wzjKVLysLRELkM5QwpDGPghHM4_xIYpxyz6ZtTJvQ==/trash_multiple" 2024/10/30 01:46:42.991112 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/Rx_6M0WyJ7RLVNoKHLVWHrt-OTtZkjS1sLgmvtUHhCrb_wzjKVLysLRELkM5QwpDGPghHM4_xIYpxyz6ZtTJvQ==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T01:46:42Z" 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/Rx_6M0WyJ7RLVNoKHLVWHrt-OTtZkjS1sLgmvtUHhCrb_wzjKVLysLRELkM5QwpDGPghHM4_xIYpxyz6ZtTJvQ==/trash_multiple" --- FAIL: TestParseSumFile (114.86s) === RUN TestCheckSumDownload run.go:180: Remote "proton drive root link ID 'rclone-test-refunod5cipi'", Local "Local file system at /tmp/rclone1475793424", Modify Window "1s" 2024/10/30 01:46:55 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-refunod5cipi/data" 2024/10/30 01:46:55 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi/data': Has cached credentials 2024/10/30 01:46:56 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi/data': Used cached credential to initialize the ProtonDrive API === RUN TestCheckSumDownload/subtest1 2024/10/30 01:48:42.968000 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:48:42.968036 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:48:55.175373 WARN RESTY 429 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/RnKvQDUCBjXuU39qyn17KREYDUhRoG7O4COub33g_sEjaduVx1xtwTerzLM6iSnxcx43gidQWr1WzpTYcjsRgA==/revisions/4JjctnZKuUlLt3-rkNA838RA5l9WhaLzDwWFA35WfbMaBvNmXjatK21dRbrQoYis1t77IE-wCosO-7ZIWOfFig==: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T01:48:55Z" 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/RnKvQDUCBjXuU39qyn17KREYDUhRoG7O4COub33g_sEjaduVx1xtwTerzLM6iSnxcx43gidQWr1WzpTYcjsRgA==/revisions/4JjctnZKuUlLt3-rkNA838RA5l9WhaLzDwWFA35WfbMaBvNmXjatK21dRbrQoYis1t77IE-wCosO-7ZIWOfFig==" 2024/10/30 01:50:01.607614 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/RnKvQDUCBjXuU39qyn17KREYDUhRoG7O4COub33g_sEjaduVx1xtwTerzLM6iSnxcx43gidQWr1WzpTYcjsRgA==/revisions/4JjctnZKuUlLt3-rkNA838RA5l9WhaLzDwWFA35WfbMaBvNmXjatK21dRbrQoYis1t77IE-wCosO-7ZIWOfFig==: Internal server error (Code=500, Status=500), Attempt 2 2024/10/30 01:50:01.607648 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/RnKvQDUCBjXuU39qyn17KREYDUhRoG7O4COub33g_sEjaduVx1xtwTerzLM6iSnxcx43gidQWr1WzpTYcjsRgA==/revisions/4JjctnZKuUlLt3-rkNA838RA5l9WhaLzDwWFA35WfbMaBvNmXjatK21dRbrQoYis1t77IE-wCosO-7ZIWOfFig==: 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-refunod5cipi'": 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/RnKvQDUCBjXuU39qyn17KREYDUhRoG7O4COub33g_sEjaduVx1xtwTerzLM6iSnxcx43gidQWr1WzpTYcjsRgA==/revisions/4JjctnZKuUlLt3-rkNA838RA5l9WhaLzDwWFA35WfbMaBvNmXjatK21dRbrQoYis1t77IE-wCosO-7ZIWOfFig==: Internal server error (Code=500, Status=500) 2024/10/30 01:50:12.645169 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/Rx_6M0WyJ7RLVNoKHLVWHrt-OTtZkjS1sLgmvtUHhCrb_wzjKVLysLRELkM5QwpDGPghHM4_xIYpxyz6ZtTJvQ==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T01:50:12Z" 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/Rx_6M0WyJ7RLVNoKHLVWHrt-OTtZkjS1sLgmvtUHhCrb_wzjKVLysLRELkM5QwpDGPghHM4_xIYpxyz6ZtTJvQ==/trash_multiple" 2024/10/30 01:50:33.517091 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/Rx_6M0WyJ7RLVNoKHLVWHrt-OTtZkjS1sLgmvtUHhCrb_wzjKVLysLRELkM5QwpDGPghHM4_xIYpxyz6ZtTJvQ==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T01:50:33Z" 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/Rx_6M0WyJ7RLVNoKHLVWHrt-OTtZkjS1sLgmvtUHhCrb_wzjKVLysLRELkM5QwpDGPghHM4_xIYpxyz6ZtTJvQ==/trash_multiple" --- FAIL: TestCheckSumDownload (229.78s) --- PASS: TestCheckSumDownload/subtest1 (3.34s) === RUN TestCopyFileCompareDest run.go:180: Remote "proton drive root link ID 'rclone-test-refunod5cipi'", Local "Local file system at /tmp/rclone1475793424", Modify Window "1s" 2024/10/30 01:50:45 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-refunod5cipi/dst" 2024/10/30 01:50:45 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi/dst': Has cached credentials 2024/10/30 01:50:46 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi/dst': Used cached credential to initialize the ProtonDrive API 2024/10/30 01:52:21 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-refunod5cipi/CompareDest" 2024/10/30 01:52:21 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi/CompareDest': Has cached credentials 2024/10/30 01:52:22 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi/CompareDest': Used cached credential to initialize the ProtonDrive API 2024/10/30 01:53:57 DEBUG : one: Need to transfer - File not found at Destination 2024/10/30 01:54:00 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/10/30 01:54:00 INFO : one: Copied (new) 2024/10/30 01:54:03 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/10/30 01:54:03.500447 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:54:03.500469 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:55:05.365277 WARN RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/7gAO532CExewmRGCYETbRlwNBakA13EQHB52gdsY_KEmhYD8N0Pwto0Y9h8pD6nkcVkt7l0ADodqsXcgGJbD0w==/revisions/OmZkLR_vRpconA3rMWtxoJSTpaMpbrE71NbSB17UwSTAhEAw_czm4ZGD5gOPnSOqScLDVf0dajkBn4aAU60RIg==: Internal server error (Code=500, Status=500), Attempt 1 2024/10/30 01:55:05.365301 ERROR RESTY 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/7gAO532CExewmRGCYETbRlwNBakA13EQHB52gdsY_KEmhYD8N0Pwto0Y9h8pD6nkcVkt7l0ADodqsXcgGJbD0w==/revisions/OmZkLR_vRpconA3rMWtxoJSTpaMpbrE71NbSB17UwSTAhEAw_czm4ZGD5gOPnSOqScLDVf0dajkBn4aAU60RIg==: Internal server error (Code=500, Status=500) 2024/10/30 01:55:05 ERROR : one: Failed to copy: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/7gAO532CExewmRGCYETbRlwNBakA13EQHB52gdsY_KEmhYD8N0Pwto0Y9h8pD6nkcVkt7l0ADodqsXcgGJbD0w==/revisions/OmZkLR_vRpconA3rMWtxoJSTpaMpbrE71NbSB17UwSTAhEAw_czm4ZGD5gOPnSOqScLDVf0dajkBn4aAU60RIg==: Internal server error (Code=500, Status=500) copy_test.go:219: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:219 Error: Received unexpected error: 500 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files/7gAO532CExewmRGCYETbRlwNBakA13EQHB52gdsY_KEmhYD8N0Pwto0Y9h8pD6nkcVkt7l0ADodqsXcgGJbD0w==/revisions/OmZkLR_vRpconA3rMWtxoJSTpaMpbrE71NbSB17UwSTAhEAw_czm4ZGD5gOPnSOqScLDVf0dajkBn4aAU60RIg==: Internal server error (Code=500, Status=500) Test: TestCopyFileCompareDest 2024/10/30 01:55:17.448678 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/Iex3hT-yzSBkiakTK6A6LcVd_2qBGmU9vd3Qj8re8QhzGD8OW1u7wN5z3sOjU3CbUVfcaf7-FaSDQep7Rdj5Ng==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 1 time="2024-10-30T01:55:17Z" 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/Iex3hT-yzSBkiakTK6A6LcVd_2qBGmU9vd3Qj8re8QhzGD8OW1u7wN5z3sOjU3CbUVfcaf7-FaSDQep7Rdj5Ng==/trash_multiple" 2024/10/30 01:55:36.700339 WARN RESTY 429 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/Iex3hT-yzSBkiakTK6A6LcVd_2qBGmU9vd3Qj8re8QhzGD8OW1u7wN5z3sOjU3CbUVfcaf7-FaSDQep7Rdj5Ng==/trash_multiple: Resource is busy. Please try again (Code=2502, Status=429), Attempt 2 time="2024-10-30T01:55:36Z" 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/Iex3hT-yzSBkiakTK6A6LcVd_2qBGmU9vd3Qj8re8QhzGD8OW1u7wN5z3sOjU3CbUVfcaf7-FaSDQep7Rdj5Ng==/trash_multiple" --- FAIL: TestCopyFileCompareDest (300.49s) FAIL 2024/10/30 01:55:45 DEBUG : proton drive root link ID 'rclone-test-refunod5cipi': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest|TestParseSumFile)$'" - Finished ERROR in 10m47.770105516s (try 3/5): exit status 1: Failed [TestParseSumFile TestCheckSumDownload TestCopyFileCompareDest]