Failed to create pull request: AggregateError

[cancan101]

Describe the bug
A clear and concise description of what the bug is.

Categorization

• This is not a permissions issue (Seek help at 403 error when Dependabot tries to create a pull request #1245)

Repository
URL: https://dev.azure.com/laticrete/Public%20Repos/

To Reproduce
Steps to reproduce the behavior:
Looks to be sporadic and due to a timeout.

Expected behavior
A clear and concise description of what you expected to happen.
Longer timeout and / or retry behavior

Logs and screenshots
https://dev.azure.com/laticrete/Public%20Repos/_build/results?buildId=10729&view=results

 - Adding dependency metadata to pull request properties...
##[error]Failed to create pull request: AggregateError
AggregateError [ETIMEDOUT]: 
    at internalConnectMultiple (node:net:1118:18)
    at internalConnectMultiple (node:net:1186:5)
    at Timeout.internalConnectMultipleTimeout (node:net:1712:5)
    at listOnTimeout (node:internal/timers:583:11)
    at process.processTimers (node:internal/timers:519:7) {
  code: 'ETIMEDOUT',
  [errors]: [
    Error: connect ETIMEDOUT 13.107.42.20:443
        at createConnectionError (node:net:1648:14)
        at Timeout.internalConnectMultipleTimeout (node:net:1707:38)
        at listOnTimeout (node:internal/timers:583:11)
        at process.processTimers (node:internal/timers:519:7) {
      errno: -110,
      code: 'ETIMEDOUT',
      syscall: 'connect',
      address: '13.107.42.20',
      port: 443
    },
    Error: connect ENETUNREACH 2620:1ec:21::20:443 - Local (:::0)
        at internalConnectMultiple (node:net:1182:16)
        at Timeout.internalConnectMultipleTimeout (node:net:1712:5)
        at listOnTimeout (node:internal/timers:583:11)
        at process.processTimers (node:internal/timers:519:7) {
      errno: -101,
      code: 'ENETUNREACH',
      syscall: 'connect',
      address: '2620:1ec:21::20',
      port: 443
    }
  ]
}

Extension (please complete the following information):

• Host: [e.g. Azure DevOps, Azure DevOps Server]
• Version [e.g. 1.2.54]

Server (please complete the following information):

• Region [e.g. westeurope]
• Version [e.g. 1.2.54]

Additional context
Add any other context about the problem here.

[rhyskoedijk]

@cancan101 thanks for the report. ENETUNREACH sounds like a low-level network issue, which is surprising to see given the previous APIs succeeded.

This could probably be fixed by adding some basic retry logic for the API calls; I will look into this when I have some free time.

[mburumaxwell]

@cancan101 how often does this happen? Is it always the same? Does retrying it fix it?

[richardjharding]

I'm also seeing the same error on every run, its very nearly the first error in the raw logs in ADO, here is the excerpt

2025-02-13T09:40:34.1718997Z ##[debug]🌎 🠊 [GET] https://***/***/***/_apis/git/repositories/***/pullrequests/11543/properties?api-version=5.0
2025-02-13T09:40:34.1725760Z ##[debug]🌎 🠊 [GET] https://***/***/***/_apis/git/repositories/***/pullrequests/11542/properties?api-version=5.0
2025-02-13T09:40:34.8739767Z ##[error]Failed to list active pull request properties: AggregateError
2025-02-13T09:40:34.8749221Z ##[debug]Processed: ##vso[task.issue type=error;source=TaskInternal;]Failed to list active pull request properties: AggregateError
2025-02-13T09:40:34.8750799Z AggregateError [ETIMEDOUT]: 
2025-02-13T09:40:34.8751245Z     at internalConnectMultiple (node:net:1118:18)
2025-02-13T09:40:34.8751650Z     at internalConnectMultiple (node:net:1186:5)
2025-02-13T09:40:34.8752079Z     at Timeout.internalConnectMultipleTimeout (node:net:1712:5)
2025-02-13T09:40:34.8752511Z     at listOnTimeout (node:internal/timers:583:11)
2025-02-13T09:40:34.8752925Z     at process.processTimers (node:internal/timers:519:7) {
2025-02-13T09:40:34.8753460Z   code: 'ETIMEDOUT',
2025-02-13T09:40:34.8753737Z   [errors]: [
2025-02-13T09:40:34.8754074Z     Error: connect ETIMEDOUT 13.107.42.20:443
2025-02-13T09:40:34.8754473Z         at createConnectionError (node:net:1648:14)
2025-02-13T09:40:34.8754931Z         at Timeout.internalConnectMultipleTimeout (node:net:1707:38)
2025-02-13T09:40:34.8755380Z         at listOnTimeout (node:internal/timers:583:11)
2025-02-13T09:40:34.8755800Z         at process.processTimers (node:internal/timers:519:7) {
2025-02-13T09:40:34.8756145Z       errno: -110,
2025-02-13T09:40:34.8756433Z       code: 'ETIMEDOUT',
2025-02-13T09:40:34.8756722Z       syscall: 'connect',
2025-02-13T09:40:34.8757030Z       address: '13.107.42.20',
2025-02-13T09:40:34.8757322Z       port: 443
2025-02-13T09:40:34.8757562Z     },
2025-02-13T09:40:34.8757905Z     Error: connect ENETUNREACH 2620:1ec:21::20:443 - Local (:::0)
2025-02-13T09:40:34.8758408Z         at internalConnectMultiple (node:net:1182:16)
2025-02-13T09:40:34.8758876Z         at Timeout.internalConnectMultipleTimeout (node:net:1712:5)
2025-02-13T09:40:34.8759318Z         at listOnTimeout (node:internal/timers:583:11)
2025-02-13T09:40:34.8759772Z         at process.processTimers (node:internal/timers:519:7) {
2025-02-13T09:40:34.8760170Z       errno: -101,
2025-02-13T09:40:34.8760461Z       code: 'ENETUNREACH',
2025-02-13T09:40:34.8760779Z       syscall: 'connect',
2025-02-13T09:40:34.8761114Z       address: '2620:1ec:21::20',
2025-02-13T09:40:34.8761476Z       port: 443
2025-02-13T09:40:34.8761770Z     }
2025-02-13T09:40:34.8762072Z   ]
2025-02-13T09:40:34.8762326Z }
2025-02-13T09:40:34.8762631Z ##[group]Job 'update-0-nuget-all'
2025-02-13T09:40:34.8763373Z ##[debug]Checking for `dependabot` install...
2025-02-13T09:40:34.8764106Z ##[debug]which 'dependabot'
2025-02-13T09:40:34.8764880Z ##[debug]not found
2025-02-13T09:40:34.8765634Z ##[debug]Dependabot CLI install was not found, installing now with `go install dependabot`...
2025-02-13T09:40:34.8766119Z ##[section]Installing Dependabot CLI
2025-02-13T09:40:34.8766677Z ##[debug]which 'go'
2025-02-13T09:40:34.8767325Z ##[debug]found: '/usr/bin/go'
2025-02-13T09:40:34.8767982Z ##[debug]which '/usr/bin/go'
2025-02-13T09:40:34.8768637Z ##[debug]found: '/usr/bin/go'
2025-02-13T09:40:34.8769380Z ##[debug]/usr/bin/go arg: ["install","github.com/dependabot/cli/cmd/dependabot@latest"]
2025-02-13T09:40:34.8770152Z ##[debug]exec tool: /usr/bin/go
2025-02-13T09:40:34.8770793Z ##[debug]arguments:
2025-02-13T09:40:34.8771813Z ##[debug]   install
2025-02-13T09:40:34.8772523Z ##[debug]   github.com/dependabot/cli/cmd/dependabot@latest
2025-02-13T09:40:34.8773112Z [command]/usr/bin/go install github.com/dependabot/cli/cmd/dependabot@latest
2025-02-13T09:40:34.8958140Z ##[debug]🌎 🠈 [200] OK
2025-02-13T09:40:34.8968412Z ##[debug]🌎 🠈 [200] OK

I should add that new pull requests get created ok

[eli-gc]

Seeing the same thing as well. I am getting this error but PRs are being created.

[rhyskoedijk]

It makes sense to add retry logic for these temporary failure HTTP response codes and I will look in to adding a PR for this shortly.
Is anybody able to confirm that retrying generally fixes the issue?

[Ossiam]

Retry generally works. I work around this issue by adding a duplicate task with the failed() condition: