Skip to content

[BUG]: RestError does not produce an error log for app configuration import #21307

@BrandonDusseau

Description

@BrandonDusseau

New issue checklist

Task name

AzureAppConfigurationImportV10

Task version

10.259.0

Issue Description

My team is encountering an issue where app config import is failing with no error logs whatsoever. This has caused us trouble in trying to figure out what the actual import problem is.

I've identified a potential cause in this line: https://github.com/microsoft/azure-pipelines-tasks/blob/6cb36a1/Tasks/AzureAppConfigurationImportV10/index.ts#L39
A call is made to tl.loc to produce a localized message, but the message is immediately discarded because there is no call to tl.error surrounding it. This is inconsistent with all of the other error handlers, so I assume it is not intentional.

I cannot say with 100% certainty that the cause of our failure is indeed a RestError. But, as this is the only failure I can see that does not log an error, I suspect this is the case.

PR for the missing error call to follow.

1_prod_centralindia.txt

Environment type (Please select at least one enviroment where you face this issue)

  • Self-Hosted
  • Microsoft Hosted
  • VMSS Pool
  • Container

Azure DevOps Server type

dev.azure.com (formerly visualstudio.com)

Azure DevOps Server Version (if applicable)

No response

Operation system

Ubuntu 22.04.5

Relevant log output

2025-09-09T13:48:45.9691836Z Azure App Configuration Import Task is starting up...
2025-09-09T13:48:45.9695485Z Azure Subscription: janus-prod
2025-09-09T13:48:45.9695722Z App Configuration Endpoint: https://bn-cin-dna-ccb-prod-app-config.azconfig.io
2025-09-09T13:48:45.9698831Z Strict: true
2025-09-09T13:48:45.9699821Z File Format: json
2025-09-09T13:48:45.9700074Z File Content Profile: appconfig/kvset
2025-09-09T13:48:45.9700533Z Dry Run: false
2025-09-09T13:48:45.9700926Z Import Mode: IgnoreMatch
2025-09-09T13:48:46.0848894Z ##[debug]MSAL - getMSALToken called. force=true
2025-09-09T13:48:46.0849692Z ##[debug]system.debug=True
2025-09-09T13:48:46.0850076Z ##[debug]system.debug=True
2025-09-09T13:48:46.0850450Z ##[debug]agent.proxyurl=undefined
2025-09-09T13:48:46.0851231Z ##[debug]agent.proxybypasslist=undefined
2025-09-09T13:48:46.0851640Z ##[debug]MSAL - ServicePrincipal - clientSecret is used.
2025-09-09T13:48:46.0861322Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Clearing cache entries created by MSAL
2025-09-09T13:48:46.0862245Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Retrieving all cache keys
2025-09-09T13:48:46.0863003Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.0863855Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Info - acquireTokenByClientCredential called
2025-09-09T13:48:46.0864498Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - initializeRequestScopes called
2025-09-09T13:48:46.0873731Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [f67b5680-9dcf-4c46-9b89-3abc07a89f99] : @azure/[email protected] : Verbose - buildOauthClientConfiguration called
2025-09-09T13:48:46.0874742Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [f67b5680-9dcf-4c46-9b89-3abc07a89f99] : @azure/[email protected] : Verbose - createAuthority called
2025-09-09T13:48:46.0880884Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Executing function authorityResolveEndpointsAsync
2025-09-09T13:48:46.0881879Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Retrieving all cache keys
2025-09-09T13:48:46.0882449Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.0882932Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Executing function authorityUpdateCloudDiscoveryMetadata
2025-09-09T13:48:46.0883474Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Attempting to get cloud discovery metadata  from authority configuration
2025-09-09T13:48:46.0884012Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Known Authorities: 
2025-09-09T13:48:46.0884480Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Authority Metadata: N/A
2025-09-09T13:48:46.0885100Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Canonical Authority: https://login.microsoftonline.com/b893e0b8-2743-4fa7-81eb-0155a9060350/
2025-09-09T13:48:46.0885707Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Did not find cloud discovery metadata in the config... Attempting to get cloud discovery metadata from the hardcoded values.
2025-09-09T13:48:46.0886250Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Found cloud discovery metadata from hardcoded values.
2025-09-09T13:48:46.0886767Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Returning result from authorityUpdateCloudDiscoveryMetadata
2025-09-09T13:48:46.0887291Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Executing function authorityUpdateEndpointMetadata
2025-09-09T13:48:46.0888104Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Attempting to get endpoint metadata from authority configuration
2025-09-09T13:48:46.0888666Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Did not find endpoint metadata in the config... Attempting to get endpoint metadata from the hardcoded values.
2025-09-09T13:48:46.0889243Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Returning result from authorityUpdateEndpointMetadata
2025-09-09T13:48:46.0890123Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Item key: authority-metadata-***-login.windows.net
2025-09-09T13:48:46.0890846Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.0891394Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Setting cache key value store
2025-09-09T13:48:46.0891881Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Returning result from authorityResolveEndpointsAsync
2025-09-09T13:48:46.0892407Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Replacing tenant domain name b893e0b8-2743-4fa7-81eb-0155a9060350 with id {tenantid}
2025-09-09T13:48:46.0894053Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [f67b5680-9dcf-4c46-9b89-3abc07a89f99] : @azure/[email protected] : Info - Building oauth client configuration with the following authority: https://login.microsoftonline.com/b893e0b8-2743-4fa7-81eb-0155a9060350/oauth2/v2.0/token.
2025-09-09T13:48:46.0897257Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [f67b5680-9dcf-4c46-9b89-3abc07a89f99] : @azure/[email protected] : Verbose - Client credential client created
2025-09-09T13:48:46.0901535Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting in-memory cache
2025-09-09T13:48:46.0902180Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.0905252Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Replacing tenant domain name b893e0b8-2743-4fa7-81eb-0155a9060350 with id {tenantid}
2025-09-09T13:48:46.0909194Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Item key: server-telemetry-***
2025-09-09T13:48:46.0910061Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.0910657Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Item key: server-telemetry-***
2025-09-09T13:48:46.0911145Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.0912781Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Verbose - Replacing tenant domain name b893e0b8-2743-4fa7-81eb-0155a9060350 with id {tenantid}
2025-09-09T13:48:46.0913403Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [f67b5680-9dcf-4c46-9b89-3abc07a89f99] : @azure/[email protected] : Info - Sending token request to endpoint: https://login.microsoftonline.com/b893e0b8-2743-4fa7-81eb-0155a9060350/oauth2/v2.0/token
2025-09-09T13:48:46.0914688Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Item key: throttling.{"clientId":"***","authority":"https://login.microsoftonline.com/b893e0b8-2743-4fa7-81eb-0155a9060350","scopes":["https://bn-cin-dna-ccb-prod-app-config.azconfig.io/.default"],"authenticationScheme":"Bearer"}
2025-09-09T13:48:46.0915300Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.2960993Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Item key: server-telemetry-***
2025-09-09T13:48:46.2962235Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.2962864Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Item key: server-telemetry-***
2025-09-09T13:48:46.2963337Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.2963976Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [f67b5680-9dcf-4c46-9b89-3abc07a89f99] : @azure/[email protected] : Warning - No client info in response
2025-09-09T13:48:46.2969102Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting in-memory cache
2025-09-09T13:48:46.2969689Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.2971820Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Item key: -login.windows.net-accesstoken-***-b893e0b8-2743-4fa7-81eb-0155a9060350-https://bn-cin-dna-ccb-prod-app-config.azconfig.io/.default--
2025-09-09T13:48:46.2972478Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Getting cache key-value store
2025-09-09T13:48:46.2973089Z ##[debug][Tue, 09 Sep 2025 13:48:46 GMT] : [] : @azure/[email protected] : Trace - Setting cache key value store
2025-09-09T13:48:46.2974161Z ##[debug]MSAL - retrieved token - isFromCache?: false
2025-09-09T13:48:48.3259564Z ##[debug]Agent environment resources - Disk: / Available 191942.98 MB out of 247919.30 MB, Memory: Used 19850.00 MB out of 128891.00 MB, CPU: Usage 21.67%
2025-09-09T13:48:51.3563277Z ##[debug]
2025-09-09T13:48:51.3564476Z ##[debug]task result: Failed
2025-09-09T13:48:51.3568213Z ##[debug]Processed: ##vso[task.complete result=Failed;done=true;]
2025-09-09T13:48:51.3740751Z ##[section]Finishing: Import kvset

Full task logs with system.debug enabled

Log file uploaded as attachment as it is too long for this field.

Repro steps

Metadata

Metadata

Assignees

No one assigned

    Labels

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions