search

Azure / azure-cli

Azure Command-Line Interface
MIT License
4.01k stars 2.98k forks source link

ACR Task fails to download context from GitHub repo containing submodule #30033

Closed monry closed 3 weeks ago

monry commented 3 weeks ago

Describe the bug

If a repository containing submodules (private or public) is specified as the context, the task execution will fail with the following message

Error: failed to download context. Please check if the URL is incorrect. If it has credentials, please check if they are expired

Related command

az acr task run --registry <registry_name> --name <created_task_name>

Errors

2024/10/06 08:10:44 Downloading source code...
Run ID: cev failed after 4s. Error: failed to download context. Please check if the URL is incorrect. If it has credentials, please check if they are expired

Issue script & Debug output

DEBUG: cli.knack.cli: Command arguments: ['acr', 'task', 'run', '--debug', '--registry', '<sensitive>', '--name', '<sensitive>']
DEBUG: cli.knack.cli: __init__ debug log:
Cannot enable color.
DEBUG: cli.knack.cli: Event: Cli.PreExecute []
DEBUG: cli.knack.cli: Event: CommandParser.OnGlobalArgumentsCreate [<function CLILogging.on_global_arguments at 0x102dd36a0>, <function OutputProducer.on_global_arguments at 0x103128c20>, <function CLIQuery.on_global_arguments at 0x103156700>]
DEBUG: cli.knack.cli: Event: CommandInvoker.OnPreCommandTableCreate []
DEBUG: cli.azure.cli.core: Modules found from index for 'acr': ['azure.cli.command_modules.acr']
DEBUG: cli.azure.cli.core: Loading command modules:
DEBUG: cli.azure.cli.core: Name                  Load Time    Groups  Commands
DEBUG: cli.azure.cli.core: acr                       0.054        36       149
DEBUG: cli.azure.cli.core: Total (1)                 0.054        36       149
DEBUG: cli.azure.cli.core: These extensions are not installed and will be skipped: ['azext_ai_examples', 'azext_next']
DEBUG: cli.azure.cli.core: Loading extensions:
DEBUG: cli.azure.cli.core: Name                  Load Time    Groups  Commands  Directory
DEBUG: cli.azure.cli.core: Total (0)                 0.000         0         0  
DEBUG: cli.azure.cli.core: Loaded 36 groups, 149 commands.
DEBUG: cli.azure.cli.core: Found a match in the command table.
DEBUG: cli.azure.cli.core: Raw command  : acr task run
DEBUG: cli.azure.cli.core: Command table: acr task run
DEBUG: cli.knack.cli: Event: CommandInvoker.OnPreCommandTableTruncate [<function AzCliLogging.init_command_file_logging at 0x1043aa520>]
DEBUG: cli.azure.cli.core.azlogging: metadata file logging enabled - writing logs to '/<sensitive>/.config/azure/<sensitive>/commands/2024-10-06.17-21-12.acr_task_run.15560.log'.
INFO: az_command_data_logger: command args: acr task run --debug --registry {} --name {}
DEBUG: cli.knack.cli: Event: CommandInvoker.OnPreArgumentLoad [<function register_global_subscription_argument.<locals>.add_subscription_parameter at 0x10440b740>]
DEBUG: cli.knack.cli: Event: CommandInvoker.OnPostArgumentLoad []
DEBUG: cli.knack.cli: Event: CommandInvoker.OnPostCommandTableCreate [<function register_ids_argument.<locals>.add_ids_arguments at 0x10440b7e0>, <function register_cache_arguments.<locals>.add_cache_arguments at 0x10440b920>, <function register_upcoming_breaking_change_info.<locals>.update_breaking_change_info at 0x10440b9c0>]
DEBUG: cli.knack.cli: Event: CommandInvoker.OnCommandTableLoaded []
DEBUG: cli.knack.cli: Event: CommandInvoker.OnPreParseArgs []
DEBUG: cli.knack.cli: Event: CommandInvoker.OnPostParseArgs [<function OutputProducer.handle_output_argument at 0x103128cc0>, <function CLIQuery.handle_query_parameter at 0x1031567a0>, <function register_ids_argument.<locals>.parse_ids_arguments at 0x10440b880>]
DEBUG: cli.azure.cli.core.commands.client_factory: Getting management service client client_type=ContainerRegistryManagementClient
DEBUG: cli.azure.cli.core.auth.persistence: build_persistence: location='/<sensitive>/.config/azure/<sensitive>/msal_token_cache.json', encrypt=False
DEBUG: cli.azure.cli.core.auth.binary_cache: load: /<sensitive>/.config/azure/<sensitive>/msal_http_cache.bin
DEBUG: urllib3.util.retry: Converted retries value: 1 -> Retry(total=1, connect=None, read=None, redirect=None, status=None)
DEBUG: msal.authority: Initializing with Entra authority: https://login.microsoftonline.com/<sensitive>
DEBUG: msal.authority: openid_config("https://login.microsoftonline.com/<sensitive>/v2.0/.well-known/openid-configuration") = {'token_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/token', 'token_endpoint_auth_methods_supported': ['client_secret_post', 'private_key_jwt', 'client_secret_basic'], 'jwks_uri': 'https://login.microsoftonline.com/<sensitive>/discovery/v2.0/keys', 'response_modes_supported': ['query', 'fragment', 'form_post'], 'subject_types_supported': ['pairwise'], 'id_token_signing_alg_values_supported': ['RS256'], 'response_types_supported': ['code', 'id_token', 'code id_token', 'id_token token'], 'scopes_supported': ['openid', 'profile', 'email', 'offline_access'], 'issuer': 'https://login.microsoftonline.com/<sensitive>/v2.0', 'request_uri_parameter_supported': False, 'userinfo_endpoint': 'https://graph.microsoft.com/oidc/userinfo', 'authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/authorize', 'device_authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/devicecode', 'http_logout_supported': True, 'frontchannel_logout_supported': True, 'end_session_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/logout', 'claims_supported': ['sub', 'iss', 'cloud_instance_name', 'cloud_instance_host_name', 'cloud_graph_host_name', 'msgraph_host', 'aud', 'exp', 'iat', 'auth_time', 'acr', 'nonce', 'preferred_username', 'name', 'tid', 'ver', 'at_hash', 'c_hash', 'email'], 'kerberos_endpoint': 'https://login.microsoftonline.com/<sensitive>/kerberos', 'tenant_region_scope': 'JPR', 'cloud_instance_name': 'microsoftonline.com', 'cloud_graph_host_name': 'graph.windows.net', 'msgraph_host': 'graph.microsoft.com', 'rbac_url': 'https://pas.windows.net'}
DEBUG: msal.application: Broker enabled? None
DEBUG: cli.azure.cli.core.commands.client_factory: Getting management service client client_type=ResourceManagementClient
DEBUG: urllib3.util.retry: Converted retries value: 1 -> Retry(total=1, connect=None, read=None, redirect=None, status=None)
DEBUG: msal.authority: Initializing with Entra authority: https://login.microsoftonline.com/<sensitive>
DEBUG: msal.authority: openid_config("https://login.microsoftonline.com/<sensitive>/v2.0/.well-known/openid-configuration") = {'token_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/token', 'token_endpoint_auth_methods_supported': ['client_secret_post', 'private_key_jwt', 'client_secret_basic'], 'jwks_uri': 'https://login.microsoftonline.com/<sensitive>/discovery/v2.0/keys', 'response_modes_supported': ['query', 'fragment', 'form_post'], 'subject_types_supported': ['pairwise'], 'id_token_signing_alg_values_supported': ['RS256'], 'response_types_supported': ['code', 'id_token', 'code id_token', 'id_token token'], 'scopes_supported': ['openid', 'profile', 'email', 'offline_access'], 'issuer': 'https://login.microsoftonline.com/<sensitive>/v2.0', 'request_uri_parameter_supported': False, 'userinfo_endpoint': 'https://graph.microsoft.com/oidc/userinfo', 'authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/authorize', 'device_authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/devicecode', 'http_logout_supported': True, 'frontchannel_logout_supported': True, 'end_session_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/logout', 'claims_supported': ['sub', 'iss', 'cloud_instance_name', 'cloud_instance_host_name', 'cloud_graph_host_name', 'msgraph_host', 'aud', 'exp', 'iat', 'auth_time', 'acr', 'nonce', 'preferred_username', 'name', 'tid', 'ver', 'at_hash', 'c_hash', 'email'], 'kerberos_endpoint': 'https://login.microsoftonline.com/<sensitive>/kerberos', 'tenant_region_scope': 'JPR', 'cloud_instance_name': 'microsoftonline.com', 'cloud_graph_host_name': 'graph.windows.net', 'msgraph_host': 'graph.microsoft.com', 'rbac_url': 'https://pas.windows.net'}
DEBUG: msal.application: Broker enabled? None
DEBUG: cli.azure.cli.core.auth.credential_adaptor: CredentialAdaptor.get_token: scopes=('https://management.core.windows.net//.default',), kwargs={}
DEBUG: cli.azure.cli.core.auth.msal_authentication: UserCredential.get_token: scopes=('https://management.core.windows.net//.default',), claims=None, kwargs={}
DEBUG: msal.application: Cache hit an AT
DEBUG: msal.telemetry: Generate or reuse correlation_id: cc577489-8b0a-41c2-860d-27f6a51d8396
DEBUG: cli.azure.cli.core.sdk.policies: Request URL: 'https://management.azure.com/subscriptions/<sensitive>/resources?$filter=resourceType%20eq%20%27Microsoft.ContainerRegistry%2Fregistries%27&api-version=2022-09-01'
DEBUG: cli.azure.cli.core.sdk.policies: Request method: 'GET'
DEBUG: cli.azure.cli.core.sdk.policies: Request headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Accept': 'application/json'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-client-request-id': 'f2d78c82-83bb-11ef-9010-3214b1c23821'
DEBUG: cli.azure.cli.core.sdk.policies:     'CommandName': 'acr task run'
DEBUG: cli.azure.cli.core.sdk.policies:     'ParameterSetName': '--debug --registry --name'
DEBUG: cli.azure.cli.core.sdk.policies:     'User-Agent': 'AZURECLI/2.64.0 (HOMEBREW) azsdk-python-core/1.28.0 Python/3.11.10 (macOS-14.6.1-arm64-arm-64bit)'
DEBUG: cli.azure.cli.core.sdk.policies:     'Authorization': '*****'
DEBUG: cli.azure.cli.core.sdk.policies: Request body:
DEBUG: cli.azure.cli.core.sdk.policies: This request has no body
DEBUG: urllib3.connectionpool: Starting new HTTPS connection (1): management.azure.com:443
DEBUG: urllib3.connectionpool: https://management.azure.com:443 "GET /subscriptions/<sensitive>/resources?$filter=resourceType%20eq%20%27Microsoft.ContainerRegistry%2Fregistries%27&api-version=2022-09-01 HTTP/1.1" 200 1085
DEBUG: cli.azure.cli.core.sdk.policies: Response status: 200
DEBUG: cli.azure.cli.core.sdk.policies: Response headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Cache-Control': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Pragma': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Length': '1085'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Type': 'application/json; charset=utf-8'
DEBUG: cli.azure.cli.core.sdk.policies:     'Expires': '-1'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-reads': '249'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-global-reads': '3749'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-request-id': '3e759191-f94f-44c8-a92f-6499c9a68f29'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-correlation-request-id': '3e759191-f94f-44c8-a92f-6499c9a68f29'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-routing-request-id': 'JAPANWEST:20241006T082113Z:3e759191-f94f-44c8-a92f-6499c9a68f29'
DEBUG: cli.azure.cli.core.sdk.policies:     'Strict-Transport-Security': 'max-age=31536000; includeSubDomains'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Content-Type-Options': 'nosniff'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Cache': 'CONFIG_NOCACHE'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-MSEdge-Ref': 'Ref A: 70A93A3E8DFF436F8292B058B86D6A9F Ref B: TYO201151003031 Ref C: 2024-10-06T08:21:13Z'
DEBUG: cli.azure.cli.core.sdk.policies:     'Date': 'Sun, 06 Oct 2024 08:21:13 GMT'
DEBUG: cli.azure.cli.core.sdk.policies: Response content:
DEBUG: cli.azure.cli.core.sdk.policies: {"value":[{"id":"/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/crgwjpew3uy","name":"crgwjpew3uy","type":"Microsoft.ContainerRegistry/registries","sku":{"name":"Premium","tier":"Premium"},"location":"japaneast","tags":{},"systemData":{"createdBy":"<sensitive>","createdByType":"User","createdAt":"2024-06-03T15:28:40.8898037Z","lastModifiedBy":"<sensitive>","lastModifiedByType":"User","lastModifiedAt":"2024-06-03T16:05:01.6765161Z"}},{"id":"/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>","name":"<sensitive>","type":"Microsoft.ContainerRegistry/registries","sku":{"name":"Premium","tier":"Premium"},"location":"japaneast","tags":{},"systemData":{"createdBy":"<sensitive>","createdByType":"User","createdAt":"2024-09-25T15:37:36.6312134Z","lastModifiedBy":"<sensitive>","lastModifiedByType":"User","lastModifiedAt":"2024-09-25T15:37:36.6312134Z"}}]}
DEBUG: cli.azure.cli.core.commands.client_factory: Getting management service client client_type=ContainerRegistryManagementClient
DEBUG: urllib3.util.retry: Converted retries value: 1 -> Retry(total=1, connect=None, read=None, redirect=None, status=None)
DEBUG: msal.authority: Initializing with Entra authority: https://login.microsoftonline.com/<sensitive>
DEBUG: msal.authority: openid_config("https://login.microsoftonline.com/<sensitive>/v2.0/.well-known/openid-configuration") = {'token_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/token', 'token_endpoint_auth_methods_supported': ['client_secret_post', 'private_key_jwt', 'client_secret_basic'], 'jwks_uri': 'https://login.microsoftonline.com/<sensitive>/discovery/v2.0/keys', 'response_modes_supported': ['query', 'fragment', 'form_post'], 'subject_types_supported': ['pairwise'], 'id_token_signing_alg_values_supported': ['RS256'], 'response_types_supported': ['code', 'id_token', 'code id_token', 'id_token token'], 'scopes_supported': ['openid', 'profile', 'email', 'offline_access'], 'issuer': 'https://login.microsoftonline.com/<sensitive>/v2.0', 'request_uri_parameter_supported': False, 'userinfo_endpoint': 'https://graph.microsoft.com/oidc/userinfo', 'authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/authorize', 'device_authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/devicecode', 'http_logout_supported': True, 'frontchannel_logout_supported': True, 'end_session_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/logout', 'claims_supported': ['sub', 'iss', 'cloud_instance_name', 'cloud_instance_host_name', 'cloud_graph_host_name', 'msgraph_host', 'aud', 'exp', 'iat', 'auth_time', 'acr', 'nonce', 'preferred_username', 'name', 'tid', 'ver', 'at_hash', 'c_hash', 'email'], 'kerberos_endpoint': 'https://login.microsoftonline.com/<sensitive>/kerberos', 'tenant_region_scope': 'JPR', 'cloud_instance_name': 'microsoftonline.com', 'cloud_graph_host_name': 'graph.windows.net', 'msgraph_host': 'graph.microsoft.com', 'rbac_url': 'https://pas.windows.net'}
DEBUG: msal.application: Broker enabled? None
DEBUG: cli.azure.cli.core.auth.credential_adaptor: CredentialAdaptor.get_token: scopes=('https://management.core.windows.net//.default',), kwargs={}
DEBUG: cli.azure.cli.core.auth.msal_authentication: UserCredential.get_token: scopes=('https://management.core.windows.net//.default',), claims=None, kwargs={}
DEBUG: msal.application: Cache hit an AT
DEBUG: msal.telemetry: Generate or reuse correlation_id: c2e1ee91-9cfe-473b-ae77-c2313729c3e5
DEBUG: cli.azure.cli.core.sdk.policies: Request URL: 'https://management.azure.com/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>?api-version=2023-11-01-preview'
DEBUG: cli.azure.cli.core.sdk.policies: Request method: 'GET'
DEBUG: cli.azure.cli.core.sdk.policies: Request headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Accept': 'application/json'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-client-request-id': 'f2d78c82-83bb-11ef-9010-3214b1c23821'
DEBUG: cli.azure.cli.core.sdk.policies:     'CommandName': 'acr task run'
DEBUG: cli.azure.cli.core.sdk.policies:     'ParameterSetName': '--debug --registry --name'
DEBUG: cli.azure.cli.core.sdk.policies:     'User-Agent': 'AZURECLI/2.64.0 (HOMEBREW) azsdk-python-core/1.28.0 Python/3.11.10 (macOS-14.6.1-arm64-arm-64bit)'
DEBUG: cli.azure.cli.core.sdk.policies:     'Authorization': '*****'
DEBUG: cli.azure.cli.core.sdk.policies: Request body:
DEBUG: cli.azure.cli.core.sdk.policies: This request has no body
DEBUG: urllib3.connectionpool: Starting new HTTPS connection (1): management.azure.com:443
DEBUG: urllib3.connectionpool: https://management.azure.com:443 "GET /subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>?api-version=2023-11-01-preview HTTP/1.1" 200 2136
DEBUG: cli.azure.cli.core.sdk.policies: Response status: 200
DEBUG: cli.azure.cli.core.sdk.policies: Response headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Cache-Control': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Pragma': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Length': '2136'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Type': 'application/json; charset=utf-8'
DEBUG: cli.azure.cli.core.sdk.policies:     'Expires': '-1'
DEBUG: cli.azure.cli.core.sdk.policies:     'Strict-Transport-Security': 'max-age=31536000; includeSubDomains'
DEBUG: cli.azure.cli.core.sdk.policies:     'api-supported-versions': '2023-11-01-preview'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-reads': '249'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-global-reads': '3749'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-request-id': '19228365-89f5-45ad-97d4-cb1e6c6e81af'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-correlation-request-id': '19228365-89f5-45ad-97d4-cb1e6c6e81af'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-routing-request-id': 'JAPANEAST:20241006T082113Z:19228365-89f5-45ad-97d4-cb1e6c6e81af'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Content-Type-Options': 'nosniff'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Cache': 'CONFIG_NOCACHE'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-MSEdge-Ref': 'Ref A: 9214F2A4E6AB4BC48E96706472D1A2AD Ref B: TYO201100115035 Ref C: 2024-10-06T08:21:13Z'
DEBUG: cli.azure.cli.core.sdk.policies:     'Date': 'Sun, 06 Oct 2024 08:21:12 GMT'
DEBUG: cli.azure.cli.core.sdk.policies: Response content:
DEBUG: cli.azure.cli.core.sdk.policies: {"sku":{"name":"Premium","tier":"Premium"},"type":"Microsoft.ContainerRegistry/registries","id":"/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>","name":"<sensitive>","location":"japaneast","tags":{},"systemData":{"createdBy":"<sensitive>","createdByType":"User","createdAt":"2024-09-25T15:37:36.6312134+00:00","lastModifiedBy":"<sensitive>","lastModifiedByType":"User","lastModifiedAt":"2024-09-25T15:37:36.6312134+00:00"},"properties":{"loginServer":"<sensitive>.azurecr.io","creationDate":"2024-09-25T15:37:36.6312134Z","provisioningState":"Succeeded","adminUserEnabled":true,"networkRuleSet":{"defaultAction":"Deny","ipRules":[]},"policies":{"quarantinePolicy":{"status":"disabled"},"trustPolicy":{"type":"Notary","status":"disabled"},"retentionPolicy":{"days":7,"lastUpdatedTime":"2024-09-25T15:37:46.2579429+00:00","status":"enabled"},"exportPolicy":{"status":"enabled"},"azureADAuthenticationAsArmPolicy":{"status":"enabled"},"softDeletePolicy":{"retentionDays":7,"lastUpdatedTime":"2024-09-25T15:37:46.2580056+00:00","status":"disabled"}},"encryption":{"status":"disabled"},"dataEndpointEnabled":false,"dataEndpointHostNames":["<sensitive>.japaneast.data.azurecr.io"],"privateEndpointConnections":[{"type":"Microsoft.ContainerRegistry/registries/privateEndpointConnections","id":"/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/privateEndpointConnections/<sensitive>.4716cfc779694bb895aab6b63b378577","name":"<sensitive>.4716cfc779694bb895aab6b63b378577","properties":{"privateEndpoint":{"id":"/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.Network/privateEndpoints/pep-mt-jpe-cr"},"privateLinkServiceConnectionState":{"status":"Approved","description":"Auto-Approved"},"provisioningState":"Succeeded"}}],"publicNetworkAccess":"Enabled","networkRuleBypassOptions":"AzureServices","zoneRedundancy":"Disabled","anonymousPullEnabled":false,"metadataSearch":"Disabled"}}
DEBUG: cli.azure.cli.core.commands.client_factory: Getting management service client client_type=ContainerRegistryManagementClient
DEBUG: urllib3.util.retry: Converted retries value: 1 -> Retry(total=1, connect=None, read=None, redirect=None, status=None)
DEBUG: msal.authority: Initializing with Entra authority: https://login.microsoftonline.com/<sensitive>
DEBUG: msal.authority: openid_config("https://login.microsoftonline.com/<sensitive>/v2.0/.well-known/openid-configuration") = {'token_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/token', 'token_endpoint_auth_methods_supported': ['client_secret_post', 'private_key_jwt', 'client_secret_basic'], 'jwks_uri': 'https://login.microsoftonline.com/<sensitive>/discovery/v2.0/keys', 'response_modes_supported': ['query', 'fragment', 'form_post'], 'subject_types_supported': ['pairwise'], 'id_token_signing_alg_values_supported': ['RS256'], 'response_types_supported': ['code', 'id_token', 'code id_token', 'id_token token'], 'scopes_supported': ['openid', 'profile', 'email', 'offline_access'], 'issuer': 'https://login.microsoftonline.com/<sensitive>/v2.0', 'request_uri_parameter_supported': False, 'userinfo_endpoint': 'https://graph.microsoft.com/oidc/userinfo', 'authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/authorize', 'device_authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/devicecode', 'http_logout_supported': True, 'frontchannel_logout_supported': True, 'end_session_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/logout', 'claims_supported': ['sub', 'iss', 'cloud_instance_name', 'cloud_instance_host_name', 'cloud_graph_host_name', 'msgraph_host', 'aud', 'exp', 'iat', 'auth_time', 'acr', 'nonce', 'preferred_username', 'name', 'tid', 'ver', 'at_hash', 'c_hash', 'email'], 'kerberos_endpoint': 'https://login.microsoftonline.com/<sensitive>/kerberos', 'tenant_region_scope': 'JPR', 'cloud_instance_name': 'microsoftonline.com', 'cloud_graph_host_name': 'graph.windows.net', 'msgraph_host': 'graph.microsoft.com', 'rbac_url': 'https://pas.windows.net'}
DEBUG: msal.application: Broker enabled? None
DEBUG: cli.azure.cli.core.commands.client_factory: Getting management service client client_type=ContainerRegistryManagementClient
DEBUG: urllib3.util.retry: Converted retries value: 1 -> Retry(total=1, connect=None, read=None, redirect=None, status=None)
DEBUG: msal.authority: Initializing with Entra authority: https://login.microsoftonline.com/<sensitive>
DEBUG: msal.authority: openid_config("https://login.microsoftonline.com/<sensitive>/v2.0/.well-known/openid-configuration") = {'token_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/token', 'token_endpoint_auth_methods_supported': ['client_secret_post', 'private_key_jwt', 'client_secret_basic'], 'jwks_uri': 'https://login.microsoftonline.com/<sensitive>/discovery/v2.0/keys', 'response_modes_supported': ['query', 'fragment', 'form_post'], 'subject_types_supported': ['pairwise'], 'id_token_signing_alg_values_supported': ['RS256'], 'response_types_supported': ['code', 'id_token', 'code id_token', 'id_token token'], 'scopes_supported': ['openid', 'profile', 'email', 'offline_access'], 'issuer': 'https://login.microsoftonline.com/<sensitive>/v2.0', 'request_uri_parameter_supported': False, 'userinfo_endpoint': 'https://graph.microsoft.com/oidc/userinfo', 'authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/authorize', 'device_authorization_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/devicecode', 'http_logout_supported': True, 'frontchannel_logout_supported': True, 'end_session_endpoint': 'https://login.microsoftonline.com/<sensitive>/oauth2/v2.0/logout', 'claims_supported': ['sub', 'iss', 'cloud_instance_name', 'cloud_instance_host_name', 'cloud_graph_host_name', 'msgraph_host', 'aud', 'exp', 'iat', 'auth_time', 'acr', 'nonce', 'preferred_username', 'name', 'tid', 'ver', 'at_hash', 'c_hash', 'email'], 'kerberos_endpoint': 'https://login.microsoftonline.com/<sensitive>/kerberos', 'tenant_region_scope': 'JPR', 'cloud_instance_name': 'microsoftonline.com', 'cloud_graph_host_name': 'graph.windows.net', 'msgraph_host': 'graph.microsoft.com', 'rbac_url': 'https://pas.windows.net'}
DEBUG: msal.application: Broker enabled? None
DEBUG: cli.azure.cli.core.auth.credential_adaptor: CredentialAdaptor.get_token: scopes=('https://management.core.windows.net//.default',), kwargs={}
DEBUG: cli.azure.cli.core.auth.msal_authentication: UserCredential.get_token: scopes=('https://management.core.windows.net//.default',), claims=None, kwargs={}
DEBUG: msal.application: Cache hit an AT
DEBUG: msal.telemetry: Generate or reuse correlation_id: 9592241a-4f41-432f-a155-e9a19888cad4
DEBUG: cli.azure.cli.core.sdk.policies: Request URL: 'https://management.azure.com/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/tasks/<sensitive>/listDetails?api-version=2019-06-01-preview'
DEBUG: cli.azure.cli.core.sdk.policies: Request method: 'POST'
DEBUG: cli.azure.cli.core.sdk.policies: Request headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Accept': 'application/json'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-client-request-id': 'f2d78c82-83bb-11ef-9010-3214b1c23821'
DEBUG: cli.azure.cli.core.sdk.policies:     'CommandName': 'acr task run'
DEBUG: cli.azure.cli.core.sdk.policies:     'ParameterSetName': '--debug --registry --name'
DEBUG: cli.azure.cli.core.sdk.policies:     'User-Agent': 'AZURECLI/2.64.0 (HOMEBREW) azsdk-python-core/1.28.0 Python/3.11.10 (macOS-14.6.1-arm64-arm-64bit)'
DEBUG: cli.azure.cli.core.sdk.policies:     'Authorization': '*****'
DEBUG: cli.azure.cli.core.sdk.policies: Request body:
DEBUG: cli.azure.cli.core.sdk.policies: This request has no body
DEBUG: urllib3.connectionpool: Starting new HTTPS connection (1): management.azure.com:443
DEBUG: urllib3.connectionpool: https://management.azure.com:443 "POST /subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/tasks/<sensitive>/listDetails?api-version=2019-06-01-preview HTTP/1.1" 200 1941
DEBUG: cli.azure.cli.core.sdk.policies: Response status: 200
DEBUG: cli.azure.cli.core.sdk.policies: Response headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Cache-Control': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Pragma': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Length': '1941'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Type': 'application/json; charset=utf-8'
DEBUG: cli.azure.cli.core.sdk.policies:     'Expires': '-1'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-writes': '199'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-request-id': '9cf1be84-a796-430d-96e3-139d61377fe2'
DEBUG: cli.azure.cli.core.sdk.policies:     'Strict-Transport-Security': 'max-age=31536000; includeSubDomains'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Content-Type-Options': 'nosniff'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-global-writes': '2999'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-correlation-request-id': '1544f273-ec91-4ec9-8310-b4605a7cc15b'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-routing-request-id': 'JAPANEAST:20241006T082114Z:1544f273-ec91-4ec9-8310-b4605a7cc15b'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Cache': 'CONFIG_NOCACHE'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-MSEdge-Ref': 'Ref A: 91616EFB3CB24E3BBB4E0131E82E2589 Ref B: TYO201100113021 Ref C: 2024-10-06T08:21:14Z'
DEBUG: cli.azure.cli.core.sdk.policies:     'Date': 'Sun, 06 Oct 2024 08:21:14 GMT'
DEBUG: cli.azure.cli.core.sdk.policies: Response content:
DEBUG: cli.azure.cli.core.sdk.policies: {"type":"Microsoft.ContainerRegistry/registries/tasks","identity":{"principalId":"<sensitive>","tenantId":"<sensitive>","type":"SystemAssigned"},"properties":{"provisioningState":"Succeeded","creationDate":"2024-10-05T12:49:31.9888199+00:00","status":"Enabled","platform":{"os":"Linux","architecture":"amd64"},"timeout":3600,"step":{"type":"Docker","imageNames":["<sensitive>.azurecr.io/acr.task.test:latest"],"isPushEnabled":true,"noCache":false,"dockerFilePath":"Dockerfile","arguments":[{"name":"NuGetPackageSourceCredentials_kidsstar","value":"'Username=<sensitive>;Password=a4nhna3awyp5zpzlvpu5tds7mrkeddi5swau7bfpdovm5rjibgla'","isSecret":true}],"contextPath":"https://github.com/kidsstar/acr.task.test.git#main:.","contextAccessToken":"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"},"trigger":{"sourceTriggers":[{"sourceRepository":{"sourceControlType":"Github","repositoryUrl":"https://github.com/kidsstar/<sensitive>.backend.git","branch":"cdktf","sourceControlAuthProperties":{"tokenType":"PAT","token":"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","expiresIn":0}},"sourceTriggerEvents":["commit"],"status":"Enabled","name":"<sensitive>.backend"}]},"credentials":{"customRegistries":{"<sensitive>.azurecr.io":{"userName":{},"password":{},"identity":"[system]"}}},"isSystemTask":false},"id":"/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/tasks/<sensitive>","name":"<sensitive>","location":"japaneast","systemData":{"createdBy":"<sensitive>","createdByType":"User","createdAt":"2024-10-05T12:49:31.5745757+00:00","lastModifiedBy":"<sensitive>","lastModifiedByType":"User","lastModifiedAt":"2024-10-06T08:09:06.3555535+00:00"}}
DEBUG: cli.azure.cli.core.auth.credential_adaptor: CredentialAdaptor.get_token: scopes=('https://management.core.windows.net//.default',), kwargs={}
DEBUG: cli.azure.cli.core.auth.msal_authentication: UserCredential.get_token: scopes=('https://management.core.windows.net//.default',), claims=None, kwargs={}
DEBUG: msal.application: Cache hit an AT
DEBUG: msal.telemetry: Generate or reuse correlation_id: 57771b98-75c3-4836-ac05-0de131f035e1
DEBUG: cli.azure.cli.core.sdk.policies: Request URL: 'https://management.azure.com/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/scheduleRun?api-version=2019-06-01-preview'
DEBUG: cli.azure.cli.core.sdk.policies: Request method: 'POST'
DEBUG: cli.azure.cli.core.sdk.policies: Request headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Type': 'application/json'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Length': '295'
DEBUG: cli.azure.cli.core.sdk.policies:     'Accept': 'application/json'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-client-request-id': 'f2d78c82-83bb-11ef-9010-3214b1c23821'
DEBUG: cli.azure.cli.core.sdk.policies:     'CommandName': 'acr task run'
DEBUG: cli.azure.cli.core.sdk.policies:     'ParameterSetName': '--debug --registry --name'
DEBUG: cli.azure.cli.core.sdk.policies:     'User-Agent': 'AZURECLI/2.64.0 (HOMEBREW) azsdk-python-core/1.28.0 Python/3.11.10 (macOS-14.6.1-arm64-arm-64bit)'
DEBUG: cli.azure.cli.core.sdk.policies:     'Authorization': '*****'
DEBUG: cli.azure.cli.core.sdk.policies: Request body:
DEBUG: cli.azure.cli.core.sdk.policies: {"type": "TaskRunRequest", "isArchiveEnabled": false, "taskId": "/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/tasks/<sensitive>", "overrideTaskStepProperties": {"arguments": [], "values": []}}
DEBUG: urllib3.connectionpool: Starting new HTTPS connection (1): management.azure.com:443
DEBUG: urllib3.connectionpool: https://management.azure.com:443 "POST /subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/scheduleRun?api-version=2019-06-01-preview HTTP/1.1" 200 514
DEBUG: cli.azure.cli.core.sdk.policies: Response status: 200
DEBUG: cli.azure.cli.core.sdk.policies: Response headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Cache-Control': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Pragma': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Length': '514'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Type': 'application/json; charset=utf-8'
DEBUG: cli.azure.cli.core.sdk.policies:     'Expires': '-1'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-writes': '199'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-request-id': '2aa6e033-1080-4e90-9372-4fc19923996d'
DEBUG: cli.azure.cli.core.sdk.policies:     'Strict-Transport-Security': 'max-age=31536000; includeSubDomains'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Content-Type-Options': 'nosniff'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-global-writes': '2999'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-correlation-request-id': '9c2bc865-176e-447a-b460-9db47852afa5'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-routing-request-id': 'JAPANEAST:20241006T082115Z:9c2bc865-176e-447a-b460-9db47852afa5'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Cache': 'CONFIG_NOCACHE'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-MSEdge-Ref': 'Ref A: 1FC8C124960A446DB6534556E75799EA Ref B: TYO201100116039 Ref C: 2024-10-06T08:21:15Z'
DEBUG: cli.azure.cli.core.sdk.policies:     'Date': 'Sun, 06 Oct 2024 08:21:15 GMT'
DEBUG: cli.azure.cli.core.sdk.policies: Response content:
DEBUG: cli.azure.cli.core.sdk.policies: {"type":"Microsoft.ContainerRegistry/registries/runs","properties":{"runId":"cey","status":"Queued","lastUpdatedTime":"2024-10-06T08:21:15+00:00","provisioningState":"Succeeded","isArchiveEnabled":false},"id":"/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/runs/cey","name":"cey","systemData":{"lastModifiedBy":"<sensitive>","lastModifiedByType":"User","lastModifiedAt":"2024-10-06T08:21:15.3002987+00:00"}}
WARNING: cli.azure.cli.command_modules.acr.task: Queued a run with ID: cey
WARNING: cli.azure.cli.command_modules.acr.task: Waiting for an agent...
DEBUG: cli.azure.cli.core.auth.credential_adaptor: CredentialAdaptor.get_token: scopes=('https://management.core.windows.net//.default',), kwargs={}
DEBUG: cli.azure.cli.core.auth.msal_authentication: UserCredential.get_token: scopes=('https://management.core.windows.net//.default',), claims=None, kwargs={}
DEBUG: msal.application: Cache hit an AT
DEBUG: msal.telemetry: Generate or reuse correlation_id: bf72efd5-17ee-495a-b866-f49e0d42a225
DEBUG: cli.azure.cli.core.sdk.policies: Request URL: 'https://management.azure.com/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/runs/cey/listLogSasUrl?api-version=2019-06-01-preview'
DEBUG: cli.azure.cli.core.sdk.policies: Request method: 'POST'
DEBUG: cli.azure.cli.core.sdk.policies: Request headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Accept': 'application/json'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-client-request-id': 'f2d78c82-83bb-11ef-9010-3214b1c23821'
DEBUG: cli.azure.cli.core.sdk.policies:     'CommandName': 'acr task run'
DEBUG: cli.azure.cli.core.sdk.policies:     'ParameterSetName': '--debug --registry --name'
DEBUG: cli.azure.cli.core.sdk.policies:     'User-Agent': 'AZURECLI/2.64.0 (HOMEBREW) azsdk-python-core/1.28.0 Python/3.11.10 (macOS-14.6.1-arm64-arm-64bit)'
DEBUG: cli.azure.cli.core.sdk.policies:     'Authorization': '*****'
DEBUG: cli.azure.cli.core.sdk.policies: Request body:
DEBUG: cli.azure.cli.core.sdk.policies: This request has no body
DEBUG: urllib3.connectionpool: Starting new HTTPS connection (1): management.azure.com:443
DEBUG: urllib3.connectionpool: https://management.azure.com:443 "POST /subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/runs/cey/listLogSasUrl?api-version=2019-06-01-preview HTTP/1.1" 200 229
DEBUG: cli.azure.cli.core.sdk.policies: Response status: 200
DEBUG: cli.azure.cli.core.sdk.policies: Response headers:
DEBUG: cli.azure.cli.core.sdk.policies:     'Cache-Control': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Pragma': 'no-cache'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Length': '229'
DEBUG: cli.azure.cli.core.sdk.policies:     'Content-Type': 'application/json; charset=utf-8'
DEBUG: cli.azure.cli.core.sdk.policies:     'Expires': '-1'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-writes': '199'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-request-id': '342c93a4-4a0a-433f-8f64-9dc5c900a8fd'
DEBUG: cli.azure.cli.core.sdk.policies:     'Strict-Transport-Security': 'max-age=31536000; includeSubDomains'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Content-Type-Options': 'nosniff'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-ratelimit-remaining-subscription-global-writes': '2999'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-correlation-request-id': 'e9fa81aa-8893-41dc-a2c6-d8b1b8421724'
DEBUG: cli.azure.cli.core.sdk.policies:     'x-ms-routing-request-id': 'JAPANEAST:20241006T082116Z:e9fa81aa-8893-41dc-a2c6-d8b1b8421724'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-Cache': 'CONFIG_NOCACHE'
DEBUG: cli.azure.cli.core.sdk.policies:     'X-MSEdge-Ref': 'Ref A: DC603E186E0B4398BC9ADE54962D023D Ref B: TYO201100113031 Ref C: 2024-10-06T08:21:15Z'
DEBUG: cli.azure.cli.core.sdk.policies:     'Date': 'Sun, 06 Oct 2024 08:21:15 GMT'
DEBUG: cli.azure.cli.core.sdk.policies: Response content:
DEBUG: cli.azure.cli.core.sdk.policies: {"logLink":"https://jpemanaged217.blob.core.windows.net/222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log?sv=2023-01-03&se=2024-10-06T09%3A31%3A15Z&sr=b&sp=r&sig=EEEARaWck1HLg405A31s06JNRXPhsxIPR%2By%2Fd5gtIlA%3D"}
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f4d14c8a-83bb-11ef-9010-3214b1c23821 Outgoing request: Method=HEAD, Path=/222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log, Query={'snapshot': None, 'timeout': None, 'restype': None, 'sv': '2023-01-03', 'se': '2024-10-06T09:31:15Z', 'sr': 'b', 'sp': 'r', 'sig': 'REDACTED'}, Headers={'x-ms-version': '2018-11-09', 'User-Agent': 'Azure-Storage/2.0.0-2.0.1 (Python CPython 3.11.10; Darwin 23.6.0)', 'x-ms-client-request-id': 'f4d14c8a-83bb-11ef-9010-3214b1c23821', 'x-ms-date': 'Sun, 06 Oct 2024 08:21:16 GMT'}.
DEBUG: urllib3.connectionpool: Starting new HTTPS connection (1): jpemanaged217.blob.core.windows.net:443
DEBUG: urllib3.connectionpool: https://jpemanaged217.blob.core.windows.net:443 "HEAD /222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log?sv=2023-01-03&se=2024-10-06T09%3A31%3A15Z&sr=b&sp=r&sig=EEEARaWck1HLg405A31s06JNRXPhsxIPR%2By%2Fd5gtIlA%3D HTTP/1.1" 404 0
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f4d14c8a-83bb-11ef-9010-3214b1c23821 Receiving Response: Server-Timestamp=Sun, 06 Oct 2024 08:21:16 GMT, Server-Request-ID=fe11cf95-901e-000d-22c8-17ecb9000000, HTTP Status Code=404, Message=The specified blob does not exist., Headers={'transfer-encoding': 'chunked', 'server': 'Windows-Azure-Blob/1.0 Microsoft-HTTPAPI/2.0', 'x-ms-request-id': 'fe11cf95-901e-000d-22c8-17ecb9000000', 'x-ms-version': '2018-11-09', 'x-ms-error-code': 'BlobNotFound', 'date': 'Sun, 06 Oct 2024 08:21:16 GMT'}.
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f4d14c8a-83bb-11ef-9010-3214b1c23821 Received expected http error: Server-Timestamp=Sun, 06 Oct 2024 08:21:16 GMT, Server-Request-ID=fe11cf95-901e-000d-22c8-17ecb9000000, HTTP status code=404, Exception=The specified blob does not exist. ErrorCode: BlobNotFound.
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f59707cc-83bb-11ef-9010-3214b1c23821 Outgoing request: Method=HEAD, Path=/222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log, Query={'snapshot': None, 'timeout': None, 'restype': None, 'sv': '2023-01-03', 'se': '2024-10-06T09:31:15Z', 'sr': 'b', 'sp': 'r', 'sig': 'REDACTED'}, Headers={'x-ms-version': '2018-11-09', 'User-Agent': 'Azure-Storage/2.0.0-2.0.1 (Python CPython 3.11.10; Darwin 23.6.0)', 'x-ms-client-request-id': 'f59707cc-83bb-11ef-9010-3214b1c23821', 'x-ms-date': 'Sun, 06 Oct 2024 08:21:17 GMT'}.
DEBUG: urllib3.connectionpool: https://jpemanaged217.blob.core.windows.net:443 "HEAD /222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log?sv=2023-01-03&se=2024-10-06T09%3A31%3A15Z&sr=b&sp=r&sig=EEEARaWck1HLg405A31s06JNRXPhsxIPR%2By%2Fd5gtIlA%3D HTTP/1.1" 404 0
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f59707cc-83bb-11ef-9010-3214b1c23821 Receiving Response: Server-Timestamp=Sun, 06 Oct 2024 08:21:17 GMT, Server-Request-ID=fe11d30f-901e-000d-2bc8-17ecb9000000, HTTP Status Code=404, Message=The specified blob does not exist., Headers={'transfer-encoding': 'chunked', 'server': 'Windows-Azure-Blob/1.0 Microsoft-HTTPAPI/2.0', 'x-ms-request-id': 'fe11d30f-901e-000d-2bc8-17ecb9000000', 'x-ms-version': '2018-11-09', 'x-ms-error-code': 'BlobNotFound', 'date': 'Sun, 06 Oct 2024 08:21:17 GMT'}.
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f59707cc-83bb-11ef-9010-3214b1c23821 Received expected http error: Server-Timestamp=Sun, 06 Oct 2024 08:21:17 GMT, Server-Request-ID=fe11d30f-901e-000d-2bc8-17ecb9000000, HTTP status code=404, Exception=The specified blob does not exist. ErrorCode: BlobNotFound.
DEBUG: cli.azure.cli.command_modules.acr._stream_utils: Failed to find new content 1 times in a row
DEBUG: cli.azure.cli.command_modules.acr._stream_utils: Base sleep time: 1, random delay: 1, total: 2, consecutive: 2
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f70c2768-83bb-11ef-9010-3214b1c23821 Outgoing request: Method=HEAD, Path=/222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log, Query={'snapshot': None, 'timeout': None, 'restype': None, 'sv': '2023-01-03', 'se': '2024-10-06T09:31:15Z', 'sr': 'b', 'sp': 'r', 'sig': 'REDACTED'}, Headers={'x-ms-version': '2018-11-09', 'User-Agent': 'Azure-Storage/2.0.0-2.0.1 (Python CPython 3.11.10; Darwin 23.6.0)', 'x-ms-client-request-id': 'f70c2768-83bb-11ef-9010-3214b1c23821', 'x-ms-date': 'Sun, 06 Oct 2024 08:21:19 GMT'}.
DEBUG: urllib3.connectionpool: https://jpemanaged217.blob.core.windows.net:443 "HEAD /222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log?sv=2023-01-03&se=2024-10-06T09%3A31%3A15Z&sr=b&sp=r&sig=EEEARaWck1HLg405A31s06JNRXPhsxIPR%2By%2Fd5gtIlA%3D HTTP/1.1" 200 0
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f70c2768-83bb-11ef-9010-3214b1c23821 Receiving Response: Server-Timestamp=Sun, 06 Oct 2024 08:21:19 GMT, Server-Request-ID=fe11dac6-901e-000d-28c8-17ecb9000000, HTTP Status Code=200, Message=OK, Headers={'content-length': '48', 'content-type': 'text/plain; charset=utf-8', 'content-encoding': 'utf-8', 'last-modified': 'Sun, 06 Oct 2024 08:21:18 GMT', 'accept-ranges': 'bytes', 'etag': '"0x8DCE5DFDA45FE9D"', 'server': 'Windows-Azure-Blob/1.0 Microsoft-HTTPAPI/2.0', 'x-ms-request-id': 'fe11dac6-901e-000d-28c8-17ecb9000000', 'x-ms-version': '2018-11-09', 'x-ms-creation-time': 'Sun, 06 Oct 2024 08:21:18 GMT', 'x-ms-lease-status': 'unlocked', 'x-ms-lease-state': 'available', 'x-ms-blob-type': 'AppendBlob', 'x-ms-blob-committed-block-count': '1', 'content-disposition': '', 'x-ms-server-encrypted': 'true', 'date': 'Sun, 06 Oct 2024 08:21:19 GMT'}.
DEBUG: cli.azure.cli.command_modules.acr._stream_utils: Failed to find new content 2 times in a row
DEBUG: cli.azure.cli.command_modules.acr._stream_utils: Base sleep time: 1, random delay: 1, total: 2, consecutive: 5
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f8c5a44e-83bb-11ef-9010-3214b1c23821 Outgoing request: Method=HEAD, Path=/222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log, Query={'snapshot': None, 'timeout': None, 'sv': '2023-01-03', 'se': '2024-10-06T09:31:15Z', 'sr': 'b', 'sp': 'r', 'sig': 'REDACTED'}, Headers={'x-ms-lease-id': None, 'If-Modified-Since': None, 'If-Unmodified-Since': None, 'If-Match': None, 'If-None-Match': None, 'x-ms-version': '2018-11-09', 'User-Agent': 'Azure-Storage/2.0.0-2.0.1 (Python CPython 3.11.10; Darwin 23.6.0)', 'x-ms-client-request-id': 'f8c5a44e-83bb-11ef-9010-3214b1c23821', 'x-ms-date': 'Sun, 06 Oct 2024 08:21:22 GMT'}.
DEBUG: urllib3.connectionpool: https://jpemanaged217.blob.core.windows.net:443 "HEAD /222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log?sv=2023-01-03&se=2024-10-06T09%3A31%3A15Z&sr=b&sp=r&sig=EEEARaWck1HLg405A31s06JNRXPhsxIPR%2By%2Fd5gtIlA%3D HTTP/1.1" 200 0
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f8c5a44e-83bb-11ef-9010-3214b1c23821 Receiving Response: Server-Timestamp=Sun, 06 Oct 2024 08:21:22 GMT, Server-Request-ID=fe11e3ed-901e-000d-3ac8-17ecb9000000, HTTP Status Code=200, Message=OK, Headers={'content-length': '207', 'content-type': 'text/plain; charset=utf-8', 'content-encoding': 'utf-8', 'last-modified': 'Sun, 06 Oct 2024 08:21:21 GMT', 'accept-ranges': 'bytes', 'etag': '"0x8DCE5DFDC24643F"', 'server': 'Windows-Azure-Blob/1.0 Microsoft-HTTPAPI/2.0', 'x-ms-request-id': 'fe11e3ed-901e-000d-3ac8-17ecb9000000', 'x-ms-version': '2018-11-09', 'x-ms-meta-Complete': 'failed', 'x-ms-creation-time': 'Sun, 06 Oct 2024 08:21:18 GMT', 'x-ms-lease-status': 'unlocked', 'x-ms-lease-state': 'available', 'x-ms-blob-type': 'AppendBlob', 'x-ms-blob-committed-block-count': '2', 'content-disposition': '', 'x-ms-server-encrypted': 'true', 'date': 'Sun, 06 Oct 2024 08:21:22 GMT'}.
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f8cdf7f2-83bb-11ef-9010-3214b1c23821 Outgoing request: Method=GET, Path=/222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log, Query={'snapshot': None, 'timeout': None, 'sv': '2023-01-03', 'se': '2024-10-06T09:31:15Z', 'sr': 'b', 'sp': 'r', 'sig': 'REDACTED'}, Headers={'x-ms-lease-id': None, 'If-Modified-Since': None, 'If-Unmodified-Since': None, 'If-Match': None, 'If-None-Match': None, 'x-ms-range': 'bytes=0-4095', 'x-ms-version': '2018-11-09', 'User-Agent': 'Azure-Storage/2.0.0-2.0.1 (Python CPython 3.11.10; Darwin 23.6.0)', 'x-ms-client-request-id': 'f8cdf7f2-83bb-11ef-9010-3214b1c23821', 'x-ms-date': 'Sun, 06 Oct 2024 08:21:22 GMT'}.
DEBUG: urllib3.connectionpool: https://jpemanaged217.blob.core.windows.net:443 "GET /222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log?sv=2023-01-03&se=2024-10-06T09%3A31%3A15Z&sr=b&sp=r&sig=EEEARaWck1HLg405A31s06JNRXPhsxIPR%2By%2Fd5gtIlA%3D HTTP/1.1" 206 207
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f8cdf7f2-83bb-11ef-9010-3214b1c23821 Receiving Response: Server-Timestamp=Sun, 06 Oct 2024 08:21:22 GMT, Server-Request-ID=fe11e41c-901e-000d-64c8-17ecb9000000, HTTP Status Code=206, Message=Partial Content, Headers={'content-length': '207', 'content-type': 'text/plain; charset=utf-8', 'content-encoding': 'utf-8', 'content-range': 'bytes 0-206/207', 'last-modified': 'Sun, 06 Oct 2024 08:21:21 GMT', 'accept-ranges': 'bytes', 'etag': '"0x8DCE5DFDC24643F"', 'server': 'Windows-Azure-Blob/1.0 Microsoft-HTTPAPI/2.0', 'x-ms-request-id': 'fe11e41c-901e-000d-64c8-17ecb9000000', 'x-ms-version': '2018-11-09', 'x-ms-meta-Complete': 'failed', 'x-ms-creation-time': 'Sun, 06 Oct 2024 08:21:18 GMT', 'x-ms-lease-status': 'unlocked', 'x-ms-lease-state': 'available', 'x-ms-blob-type': 'AppendBlob', 'x-ms-blob-committed-block-count': '2', 'content-disposition': '', 'x-ms-server-encrypted': 'true', 'date': 'Sun, 06 Oct 2024 08:21:22 GMT'}.
2024/10/06 08:21:18 Downloading source code...
Run ID: cey failed after 3s. Error: failed to download context. Please check if the URL is incorrect. If it has credentials, please check if they are expired
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f8d6cab2-83bb-11ef-9010-3214b1c23821 Outgoing request: Method=HEAD, Path=/222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log, Query={'snapshot': None, 'timeout': None, 'sv': '2023-01-03', 'se': '2024-10-06T09:31:15Z', 'sr': 'b', 'sp': 'r', 'sig': 'REDACTED'}, Headers={'x-ms-lease-id': None, 'If-Modified-Since': None, 'If-Unmodified-Since': None, 'If-Match': None, 'If-None-Match': None, 'x-ms-version': '2018-11-09', 'User-Agent': 'Azure-Storage/2.0.0-2.0.1 (Python CPython 3.11.10; Darwin 23.6.0)', 'x-ms-client-request-id': 'f8d6cab2-83bb-11ef-9010-3214b1c23821', 'x-ms-date': 'Sun, 06 Oct 2024 08:21:22 GMT'}.
DEBUG: urllib3.connectionpool: https://jpemanaged217.blob.core.windows.net:443 "HEAD /222298279de64df094cae28eb5817170-4foivfommn/logs/cey/rawtext.log?sv=2023-01-03&se=2024-10-06T09%3A31%3A15Z&sr=b&sp=r&sig=EEEARaWck1HLg405A31s06JNRXPhsxIPR%2By%2Fd5gtIlA%3D HTTP/1.1" 200 0
INFO: azure.multiapi.storage.v2018_11_09.common.storageclient: Client-Request-ID=f8d6cab2-83bb-11ef-9010-3214b1c23821 Receiving Response: Server-Timestamp=Sun, 06 Oct 2024 08:21:22 GMT, Server-Request-ID=fe11e44a-901e-000d-0ac8-17ecb9000000, HTTP Status Code=200, Message=OK, Headers={'content-length': '207', 'content-type': 'text/plain; charset=utf-8', 'content-encoding': 'utf-8', 'last-modified': 'Sun, 06 Oct 2024 08:21:21 GMT', 'accept-ranges': 'bytes', 'etag': '"0x8DCE5DFDC24643F"', 'server': 'Windows-Azure-Blob/1.0 Microsoft-HTTPAPI/2.0', 'x-ms-request-id': 'fe11e44a-901e-000d-0ac8-17ecb9000000', 'x-ms-version': '2018-11-09', 'x-ms-meta-Complete': 'failed', 'x-ms-creation-time': 'Sun, 06 Oct 2024 08:21:18 GMT', 'x-ms-lease-status': 'unlocked', 'x-ms-lease-state': 'available', 'x-ms-blob-type': 'AppendBlob', 'x-ms-blob-committed-block-count': '2', 'content-disposition': '', 'x-ms-server-encrypted': 'true', 'date': 'Sun, 06 Oct 2024 08:21:22 GMT'}.
DEBUG: cli.azure.cli.command_modules.acr._stream_utils: status was: 'failed'
DEBUG: cli.azure.cli.core.azclierror: Traceback (most recent call last):
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/knack/cli.py", line 233, in invoke
    cmd_result = self.invocation.execute(args)
                 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/core/commands/__init__.py", line 666, in execute
    raise ex
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/core/commands/__init__.py", line 733, in _run_jobs_serially
    results.append(self._run_job(expanded_arg, cmd_copy))
                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/core/commands/__init__.py", line 703, in _run_job
    result = cmd_copy(params)
             ^^^^^^^^^^^^^^^^
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/core/commands/__init__.py", line 336, in __call__
    return self.handler(*args, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/core/commands/command_operation.py", line 121, in handler
    return op(**command_args)
           ^^^^^^^^^^^^^^^^^^
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/command_modules/acr/task.py", line 1001, in acr_task_run
    return stream_logs(cmd, client, run_id, registry_name, resource_group_name, timeout, no_format, True)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/command_modules/acr/_stream_utils.py", line 58, in stream_logs
    _stream_logs(no_format,
  File "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/command_modules/acr/_stream_utils.py", line 214, in _stream_logs
    raise CLIError("Run failed")
knack.util.CLIError: Run failed

ERROR: cli.azure.cli.core.azclierror: Run failed
ERROR: az_command_data_logger: Run failed
DEBUG: cli.knack.cli: Event: Cli.PostExecute [<function AzCliLogging.deinit_cmd_metadata_logging at 0x1043aa7a0>]
INFO: az_command_data_logger: exit code: 1
INFO: cli.__main__: Command ran in 10.392 seconds (init: 0.069, invoke: 10.323)
INFO: telemetry.main: Begin splitting cli events and extra events, total events: 1
INFO: telemetry.client: Accumulated 0 events. Flush the clients.
INFO: telemetry.main: Finish splitting cli events and extra events, cli events: 1
INFO: telemetry.save: Save telemetry record of length 3889 in cache file under /<sensitive>/.config/azure/<sensitive>/telemetry/20241006172123179
INFO: telemetry.main: Begin creating telemetry upload process.
INFO: telemetry.process: Creating upload process: "/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/bin/python /opt/homebrew/Cellar/azure-cli/2.64.0/libexec/lib/python3.11/site-packages/azure/cli/telemetry/__init__.py /<sensitive>/.config/azure/<sensitive> /<sensitive>/.config/azure/<sensitive>/telemetry/20241006172123179"
INFO: telemetry.process: Return from creating process 15625
INFO: telemetry.main: Finish creating telemetry upload process.

Expected behavior

Task execution succeeds even when a repository containing submodules (public or private) is specified as Context.

Environment Summary

azure-cli                         2.64.0

core                              2.64.0
telemetry                          1.1.0

Extensions:
account                            0.2.5

Dependencies:
msal                              1.30.0
azure-mgmt-resource               23.1.1

Python location '/opt/homebrew/Cellar/azure-cli/2.64.0/libexec/bin/python'
Extensions directory '/Users/monry/.config/azure/gokkoworld/cliextensions'

Python (Darwin) 3.11.10 (main, Sep  7 2024, 01:03:31) [Clang 15.0.0 (clang-1500.3.9.4)]

Legal docs and information: aka.ms/AzureCliLegal

Your CLI is up-to-date.

Additional context

The task settings are as follows

{
  "agentConfiguration": null,
  "agentPoolName": null,
  "creationDate": "2024-10-05T12:49:31.988819+00:00",
  "credentials": {
    "customRegistries": {
      "<sensitive>.azurecr.io": null
    },
    "sourceRegistry": null
  },
  "id": "/subscriptions/<sensitive>/resourceGroups/<sensitive>/providers/Microsoft.ContainerRegistry/registries/<sensitive>/tasks/<sensitive>",
  "identity": {
    "principalId": "<sensitive>",
    "tenantId": "<sensitive>",
    "type": "SystemAssigned",
    "userAssignedIdentities": null
  },
  "isSystemTask": false,
  "location": "japaneast",
  "logTemplate": null,
  "name": "<sensitive>",
  "platform": {
    "architecture": "amd64",
    "os": "Linux",
    "variant": null
  },
  "provisioningState": "Succeeded",
  "resourceGroup": "<sensitive>",
  "status": "Enabled",
  "step": {
    "arguments": [
      {
        "isSecret": true,
        "name": "NuGetPackageSourceCredentials_<sensitive>",
        "value": null
      }
    ],
    "baseImageDependencies": null,
    "contextAccessToken": null,
    "contextPath": "https://github.com/<sensitive>/acr.task.test.git#main:.",
    "dockerFilePath": "Dockerfile",
    "imageNames": [
      "<sensitive>.azurecr.io/acr.task.test:latest"
    ],
    "isPushEnabled": true,
    "noCache": false,
    "target": null,
    "type": "Docker"
  },
  "systemData": {
    "createdAt": "2024-10-05T12:49:31.574575+00:00",
    "createdBy": "<sensitive>",
    "createdByType": "User",
    "lastModifiedAt": "2024-10-06T08:09:06.355553+00:00",
    "lastModifiedBy": "<sensitive>",
    "lastModifiedByType": "User"
  },
  "tags": null,
  "timeout": 3600,
  "trigger": {
    "baseImageTrigger": null,
    "sourceTriggers": [
      {
        "name": "<sensitive>",
        "sourceRepository": {
          "branch": "cdktf",
          "repositoryUrl": "https://github.com/<sensitive>/<sensitive>.git",
          "sourceControlAuthProperties": null,
          "sourceControlType": "Github"
        },
        "sourceTriggerEvents": [
          "commit"
        ],
        "status": "Enabled"
      }
    ],
    "timerTriggers": null
  },
  "type": "Microsoft.ContainerRegistry/registries/tasks"
}

This issue seems to be related to the comment here. It seems to be an ongoing problem as that issue was closed without final resolution.

yonzhan commented 3 weeks ago

Thank you for opening this issue, we will look into it.

github-actions[bot] commented 3 weeks ago

Here are some similar issues that might help you. Please check if they can solve your problem.

monry commented 3 weeks ago

I'm sorry! I was investigating the issue afterwards, and it resolved itself. The cause was that I was using git@github.com:<owner>/<repo> instead of https://github.com/<owner>/<repo> as the URL for the submodule.