(expand)"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this (expand)" is waiting for "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/hashicorp/azurerm\"] (close)" is waiting for "data.azurerm_resource_group.rg-devops-arm-poc (expand)"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "data.azurerm_resource_group.rg-devops-arm-poc (expand)" is waiting for "provider[\"registry.terraform.io/hashicorp/azurerm\"]"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "module.service_connection (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this (expand)" is waiting for "module.service_connection.var.service_connection_params (expand)"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/hashicorp/azurerm\"] (close)"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]" is waiting for "module.service_connection.azuredevops_serviceendpoint_azurerm.this (expand)"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "module.service_connection.var.service_connection_params (expand)" is waiting for "data.azurerm_resource_group.rg-devops-arm-poc (expand)"
2024-09-20T12:08:33.581Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/microsoft/azuredevops\"] (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:33.814Z [DEBUG] provider.terraform-provider-azurerm_v4.0.1_x5: [DEBUG] Determining which Resource Providers require Registration
2024-09-20T12:08:33.815Z [DEBUG] provider.terraform-provider-azurerm_v4.0.1_x5: [DEBUG] All required Resource Providers are registered
2024-09-20T12:08:33.815Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Called downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.33.0/helper/schema/grpc_provider.go:612 tf_mux_provider="*schema.GRPCProviderServer" tf_rpc=ConfigureProvider tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @module=sdk.helper_schema timestamp=2024-09-20T12:08:33.814Z
2024-09-20T12:08:33.815Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: calling downstream server: @module=sdk.mux tf_mux_provider="*proto5server.Server" tf_rpc=ConfigureProvider @caller=github.com/hashicorp/terraform-plugin-mux@v0.15.0/internal/logging/mux.go:19 timestamp=2024-09-20T12:08:33.815Z
2024-09-20T12:08:33.815Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Checking ProviderSchema lock: tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=ConfigureProvider @module=sdk.framework tf_mux_provider="*proto5server.Server" tf_provider_addr=registry.terraform.io/hashicorp/azurerm @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwserver/server.go:342 timestamp=2024-09-20T12:08:33.815Z
2024-09-20T12:08:33.815Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 @module=sdk.framework description=configuration tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_rpc=ConfigureProvider tf_attribute_path=features[0].recovery_service tf_mux_provider="*proto5server.Server" timestamp=2024-09-20T12:08:33.815Z
2024-09-20T12:08:33.816Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: tf_mux_provider="*proto5server.Server" tf_rpc=ConfigureProvider @module=sdk.framework description=configuration tf_attribute_path=features[0].resource_group tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 timestamp=2024-09-20T12:08:33.815Z
2024-09-20T12:08:33.816Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: tf_rpc=ConfigureProvider @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 @module=sdk.framework description=configuration tf_attribute_path=features[0].template_deployment tf_mux_provider="*proto5server.Server" tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 timestamp=2024-09-20T12:08:33.815Z
2024-09-20T12:08:33.816Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: @module=sdk.framework description=configuration tf_attribute_path=features[0].api_management tf_mux_provider="*proto5server.Server" tf_rpc=ConfigureProvider @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 timestamp=2024-09-20T12:08:33.815Z
2024-09-20T12:08:33.816Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 @module=sdk.framework tf_provider_addr=registry.terraform.io/hashicorp/azurerm description=configuration tf_attribute_path=features[0].application_insights tf_mux_provider="*proto5server.Server" tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=ConfigureProvider timestamp=2024-09-20T12:08:33.815Z
2024-09-20T12:08:33.816Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: @module=sdk.framework description=configuration tf_attribute_path=features[0].machine_learning tf_mux_provider="*proto5server.Server" tf_provider_addr=registry.terraform.io/hashicorp/azurerm @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=ConfigureProvider timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: tf_attribute_path=features[0].postgresql_flexible_server tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 @module=sdk.framework description=configuration tf_mux_provider="*proto5server.Server" tf_rpc=ConfigureProvider timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: description=configuration tf_attribute_path=features[0].virtual_machine_scale_set tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=ConfigureProvider @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 @module=sdk.framework tf_mux_provider="*proto5server.Server" tf_provider_addr=registry.terraform.io/hashicorp/azurerm timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=ConfigureProvider @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 tf_attribute_path=features[0].key_vault tf_mux_provider="*proto5server.Server" tf_provider_addr=registry.terraform.io/hashicorp/azurerm @module=sdk.framework description=configuration timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: @module=sdk.framework description=configuration tf_mux_provider="*proto5server.Server" tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=ConfigureProvider @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 tf_attribute_path=features[0].managed_disk timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: tf_attribute_path=features[0].recovery_services_vaults tf_mux_provider="*proto5server.Server" tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @module=sdk.framework description=configuration tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_rpc=ConfigureProvider @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: tf_attribute_path=features[0].app_configuration tf_mux_provider="*proto5server.Server" tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_rpc=ConfigureProvider @module=sdk.framework description=configuration timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: @module=sdk.framework description=configuration tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 tf_attribute_path=features[0].cognitive_account tf_mux_provider="*proto5server.Server" tf_rpc=ConfigureProvider timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: description=configuration tf_mux_provider="*proto5server.Server" tf_provider_addr=registry.terraform.io/hashicorp/azurerm @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 @module=sdk.framework tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_attribute_path=features[0].log_analytics_workspace tf_rpc=ConfigureProvider timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: description=configuration tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 @module=sdk.framework tf_attribute_path=features[0].subscription tf_mux_provider="*proto5server.Server" tf_rpc=ConfigureProvider timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Transforming empty block to null block: tf_mux_provider="*proto5server.Server" @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwschemadata/data_nullify_collection_blocks.go:90 @module=sdk.framework tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=ConfigureProvider description=configuration tf_attribute_path=features[0].virtual_machine timestamp=2024-09-20T12:08:33.816Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Calling provider defined Provider Configure: @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwserver/server_configureprovider.go:15 @module=sdk.framework tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_mux_provider="*proto5server.Server" tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=ConfigureProvider timestamp=2024-09-20T12:08:33.817Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Called provider defined Provider Configure: tf_mux_provider="*proto5server.Server" tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_rpc=ConfigureProvider @caller=github.com/hashicorp/terraform-plugin-framework@v1.8.0/internal/fwserver/server_configureprovider.go:23 @module=sdk.framework tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 timestamp=2024-09-20T12:08:33.817Z
2024-09-20T12:08:33.817Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Received downstream response: diagnostic_error_count=0 tf_provider_addr=registry.terraform.io/hashicorp/azurerm tf_req_duration_ms=4890 tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 @caller=github.com/hashicorp/terraform-plugin-go@v0.23.0/tfprotov5/internal/tf5serverlogging/downstream_request.go:42 @module=sdk.proto diagnostic_warning_count=0 tf_proto_version=5.6 tf_rpc=Configure timestamp=2024-09-20T12:08:33.817Z
2024-09-20T12:08:33.818Z [TRACE] provider.terraform-provider-azurerm_v4.0.1_x5: Served request: tf_provider_addr=registry.terraform.io/hashicorp/azurerm @module=sdk.proto tf_proto_version=5.6 @caller=github.com/hashicorp/terraform-plugin-go@v0.23.0/tfprotov5/tf5server/server.go:598 tf_req_id=c6f721db-f3fe-6e7b-73d8-61a8466bac36 tf_rpc=Configure timestamp=2024-09-20T12:08:33.817Z
2024-09-20T12:08:33.818Z [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/azurerm\"]": visit complete
2024-09-20T12:08:33.818Z [TRACE] vertex "data.azurerm_resource_group.rg-devops-arm-poc (expand)": starting visit (*terraform.nodeExpandApplyableResource)
2024-09-20T12:08:33.818Z [TRACE] vertex "data.azurerm_resource_group.rg-devops-arm-poc (expand)": does not belong to any module instance
2024-09-20T12:08:33.818Z [TRACE] vertex "data.azurerm_resource_group.rg-devops-arm-poc (expand)": visit complete
2024-09-20T12:08:33.818Z [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/azurerm\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2024-09-20T12:08:33.818Z [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/azurerm\"] (close)": does not belong to any module instance
2024-09-20T12:08:33.818Z [TRACE] GRPCProvider: Close
2024-09-20T12:08:33.818Z [TRACE] vertex "module.service_connection.var.service_connection_params (expand)": starting visit (*terraform.nodeExpandModuleVariable)
2024-09-20T12:08:33.818Z [TRACE] vertex "module.service_connection.var.service_connection_params (expand)": does not belong to any module instance
2024-09-20T12:08:33.818Z [TRACE] vertex "module.service_connection.var.service_connection_params (expand)": expanding dynamic subgraph
2024-09-20T12:08:33.819Z [TRACE] vertex "module.service_connection.var.service_connection_params (expand)": entering dynamic subgraph
2024-09-20T12:08:33.819Z [TRACE] vertex "module.service_connection.var.service_connection_params": starting visit (*terraform.nodeModuleVariable)
2024-09-20T12:08:33.819Z [TRACE] vertex "module.service_connection.var.service_connection_params": belongs to
2024-09-20T12:08:33.819Z [TRACE] nodeModuleVariable: evaluating module.service_connection.var.service_connection_params
2024-09-20T12:08:33.819Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/hashicorp/azurerm" is in the global cache
2024-09-20T12:08:33.819Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.819Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.819Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/hashicorp/azurerm" is in the global cache
2024-09-20T12:08:33.819Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.819Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/hashicorp/azurerm" is in the global cache
2024-09-20T12:08:33.819Z [TRACE] prepareFinalInputVariableValue: preparing module.service_connection.var.service_connection_params
2024-09-20T12:08:33.819Z [TRACE] vertex "module.service_connection.var.service_connection_params": visit complete
2024-09-20T12:08:33.819Z [TRACE] vertex "root": starting visit (terraform.graphNodeRoot)
2024-09-20T12:08:33.819Z [TRACE] vertex "root": does not belong to any module instance
2024-09-20T12:08:33.819Z [TRACE] vertex "root": visit complete
2024-09-20T12:08:33.819Z [TRACE] vertex "module.service_connection.var.service_connection_params (expand)": dynamic subgraph completed successfully
2024-09-20T12:08:33.819Z [TRACE] vertex "module.service_connection.var.service_connection_params (expand)": visit complete
2024-09-20T12:08:33.819Z [TRACE] vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this (expand)": starting visit (*terraform.nodeExpandApplyableResource)
2024-09-20T12:08:33.819Z [TRACE] vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this (expand)": does not belong to any module instance
2024-09-20T12:08:33.819Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.820Z [TRACE] vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this (expand)": visit complete
2024-09-20T12:08:33.820Z [TRACE] vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]": starting visit (*terraform.NodeApplyableResourceInstance)
2024-09-20T12:08:33.820Z [TRACE] vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]": belongs to module.service_connection
2024-09-20T12:08:33.820Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2024-09-20T12:08:33.843Z [ERROR] provider: error encountered while scanning stdout: error="read |0: file already closed"
2024-09-20T12:08:33.843Z [INFO]  provider: plugin process exited: plugin=.terraform/providers/registry.terraform.io/hashicorp/azurerm/4.0.1/linux_amd64/terraform-provider-azurerm_v4.0.1_x5 id=1019
2024-09-20T12:08:33.844Z [DEBUG] provider: plugin exited
2024-09-20T12:08:33.844Z [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/azurerm\"] (close)": visit complete
2024-09-20T12:08:33.844Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.844Z [TRACE] readDiff: Read Create change from plan for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:33.844Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.844Z [TRACE] readResourceInstanceState: reading state for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:33.844Z [TRACE] readResourceInstanceState: no state present for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:33.844Z [TRACE] readDiff: Read Create change from plan for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:33.844Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.844Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.845Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.845Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.845Z [TRACE] Re-validating config for "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]"
2024-09-20T12:08:33.845Z [TRACE] GRPCProvider: ValidateResourceConfig
2024-09-20T12:08:33.845Z [TRACE] GRPCProvider: GetProviderSchema
2024-09-20T12:08:33.845Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Received request: @module=sdk.proto tf_proto_version=5.3 tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/tf5server/server.go:679 tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=f615abd1-a141-7773-3b7a-04cf4d0fcd7a tf_resource_type=azuredevops_serviceendpoint_azurerm timestamp=2024-09-20T12:08:33.845Z
2024-09-20T12:08:33.846Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Sending request downstream: tf_rpc=ValidateResourceTypeConfig tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=f615abd1-a141-7773-3b7a-04cf4d0fcd7a tf_resource_type=azuredevops_serviceendpoint_azurerm @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 timestamp=2024-09-20T12:08:33.845Z
2024-09-20T12:08:33.848Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Calling downstream: tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.23.0/helper/schema/grpc_provider.go:245 tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_resource_type=azuredevops_serviceendpoint_azurerm @module=sdk.helper_schema tf_req_id=f615abd1-a141-7773-3b7a-04cf4d0fcd7a timestamp=2024-09-20T12:08:33.846Z
2024-09-20T12:08:33.848Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Called downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.23.0/helper/schema/grpc_provider.go:247 @module=sdk.helper_schema tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_resource_type=azuredevops_serviceendpoint_azurerm tf_req_id=f615abd1-a141-7773-3b7a-04cf4d0fcd7a tf_rpc=ValidateResourceTypeConfig timestamp=2024-09-20T12:08:33.848Z
2024-09-20T12:08:33.848Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Received downstream response: tf_resource_type=azuredevops_serviceendpoint_azurerm @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 @module=sdk.proto tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=f615abd1-a141-7773-3b7a-04cf4d0fcd7a tf_rpc=ValidateResourceTypeConfig diagnostic_error_count=0 diagnostic_warning_count=0 tf_proto_version=5.3 tf_req_duration_ms=2 timestamp=2024-09-20T12:08:33.848Z
2024-09-20T12:08:33.848Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Served request: tf_resource_type=azuredevops_serviceendpoint_azurerm tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/tf5server/server.go:699 tf_proto_version=5.3 @module=sdk.proto tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=f615abd1-a141-7773-3b7a-04cf4d0fcd7a timestamp=2024-09-20T12:08:33.848Z
2024-09-20T12:08:33.848Z [TRACE] GRPCProvider: PlanResourceChange
2024-09-20T12:08:33.848Z [TRACE] GRPCProvider: GetProviderSchema
2024-09-20T12:08:33.849Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Received request: tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_resource_type=azuredevops_serviceendpoint_azurerm @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/tf5server/server.go:770 @module=sdk.proto tf_proto_version=5.3 tf_req_id=333b036c-9ec9-7ee6-d742-761417e57aa1 tf_rpc=PlanResourceChange timestamp=2024-09-20T12:08:33.849Z
2024-09-20T12:08:33.850Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Sending request downstream: tf_resource_type=azuredevops_serviceendpoint_azurerm tf_rpc=PlanResourceChange @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 tf_proto_version=5.3 tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=333b036c-9ec9-7ee6-d742-761417e57aa1 @module=sdk.proto timestamp=2024-09-20T12:08:33.849Z
2024-09-20T12:08:33.861Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Received downstream response: tf_req_id=333b036c-9ec9-7ee6-d742-761417e57aa1 tf_resource_type=azuredevops_serviceendpoint_azurerm tf_rpc=PlanResourceChange @module=sdk.proto tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_duration_ms=7 diagnostic_warning_count=0 tf_proto_version=5.3 @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 diagnostic_error_count=0 timestamp=2024-09-20T12:08:33.857Z
2024-09-20T12:08:33.861Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Served request: tf_rpc=PlanResourceChange tf_proto_version=5.3 tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=333b036c-9ec9-7ee6-d742-761417e57aa1 tf_resource_type=azuredevops_serviceendpoint_azurerm @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/tf5server/server.go:796 @module=sdk.proto timestamp=2024-09-20T12:08:33.857Z
2024-09-20T12:08:33.862Z [WARN]  Provider "registry.terraform.io/microsoft/azuredevops" produced an invalid plan for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"], but we are tolerating it because it is using the legacy plugin SDK.
    The following problems may be the cause of any confusing errors from downstream operations:
      - .description: planned value cty.StringVal("Managed by Terraform") for a non-computed attribute
      - .environment: planned value cty.StringVal("AzureCloud") for a non-computed attribute
      - .service_endpoint_authentication_scheme: planned value cty.StringVal("ServicePrincipal") for a non-computed attribute
2024-09-20T12:08:33.863Z [TRACE] checkPlannedChange: Verifying that actual change (action Create) matches planned change (action Create)
module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]: Creating...
2024-09-20T12:08:33.864Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.864Z [INFO]  Starting apply for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:33.864Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.864Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:33.864Z [DEBUG] module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]: applying the planned Create change
2024-09-20T12:08:33.864Z [TRACE] GRPCProvider: ApplyResourceChange
2024-09-20T12:08:33.864Z [TRACE] GRPCProvider: GetProviderSchema
2024-09-20T12:08:33.866Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Received request: @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/tf5server/server.go:805 @module=sdk.proto tf_resource_type=azuredevops_serviceendpoint_azurerm tf_proto_version=5.3 tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=2437da30-d37f-8cab-3289-cbe1e195a4fd tf_rpc=ApplyResourceChange timestamp=2024-09-20T12:08:33.865Z
2024-09-20T12:08:33.866Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Sending request downstream: tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=2437da30-d37f-8cab-3289-cbe1e195a4fd tf_resource_type=azuredevops_serviceendpoint_azurerm tf_rpc=ApplyResourceChange tf_proto_version=5.3 @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto timestamp=2024-09-20T12:08:33.865Z
2024-09-20T12:08:33.867Z [INFO]  provider.terraform-provider-azuredevops_v1.3.0: 2024/09/20 12:08:33 [DEBUG] setting computed for "authorization" from ComputedKeys: timestamp=2024-09-20T12:08:33.865Z
2024-09-20T12:08:33.867Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Calling downstream: tf_resource_type=azuredevops_serviceendpoint_azurerm @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.23.0/helper/schema/resource.go:836 tf_req_id=2437da30-d37f-8cab-3289-cbe1e195a4fd @module=sdk.helper_schema tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_rpc=ApplyResourceChange timestamp=2024-09-20T12:08:33.865Z
2024-09-20T12:08:34.151Z [INFO]  provider.terraform-provider-azuredevops_v1.3.0: 2024/09/20 12:08:34 [DEBUG] Waiting for state to become: [Ready Failed]: timestamp=2024-09-20T12:08:34.149Z
2024-09-20T12:08:38.584Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/microsoft/azuredevops\"] (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:38.584Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this (expand)" is waiting for "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]"
2024-09-20T12:08:38.584Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]" is waiting for "module.service_connection.azuredevops_resource_authorization.this (expand)"
2024-09-20T12:08:38.584Z [TRACE] dag/walk: vertex "module.service_connection (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:38.846Z [TRACE] dag/walk: vertex "root" is waiting for "module.service_connection (close)"
2024-09-20T12:08:43.590Z [TRACE] dag/walk: vertex "module.service_connection (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:43.590Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this (expand)" is waiting for "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]"
2024-09-20T12:08:43.590Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]" is waiting for "module.service_connection.azuredevops_resource_authorization.this (expand)"
2024-09-20T12:08:43.590Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/microsoft/azuredevops\"] (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:43.848Z [TRACE] dag/walk: vertex "root" is waiting for "module.service_connection (close)"
module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]: Still creating... [10s elapsed]
2024-09-20T12:08:44.321Z [INFO]  provider.terraform-provider-azuredevops_v1.3.0: 2024/09/20 12:08:44 [DEBUG] Waiting for state to become: [Ready Failed]: timestamp=2024-09-20T12:08:44.320Z
2024-09-20T12:08:48.594Z [TRACE] dag/walk: vertex "module.service_connection (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:48.594Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]" is waiting for "module.service_connection.azuredevops_resource_authorization.this (expand)"
2024-09-20T12:08:48.594Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/microsoft/azuredevops\"] (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:48.594Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this (expand)" is waiting for "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]"
2024-09-20T12:08:48.848Z [TRACE] dag/walk: vertex "root" is waiting for "module.service_connection (close)"
2024-09-20T12:08:53.598Z [TRACE] dag/walk: vertex "module.service_connection (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:53.598Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this (expand)" is waiting for "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]"
2024-09-20T12:08:53.599Z [TRACE] dag/walk: vertex "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]" is waiting for "module.service_connection.azuredevops_resource_authorization.this (expand)"
2024-09-20T12:08:53.599Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/microsoft/azuredevops\"] (close)" is waiting for "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]"
2024-09-20T12:08:53.850Z [TRACE] dag/walk: vertex "root" is waiting for "module.service_connection (close)"
module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]: Still creating... [20s elapsed]
2024-09-20T12:08:54.361Z [TRACE] maybeTainted: module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"] encountered an error during creation, so it is now marked as tainted
2024-09-20T12:08:54.363Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:54.363Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Called downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.23.0/helper/schema/resource.go:838 tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=2437da30-d37f-8cab-3289-cbe1e195a4fd tf_resource_type=azuredevops_serviceendpoint_azurerm tf_rpc=ApplyResourceChange @module=sdk.helper_schema timestamp=2024-09-20T12:08:54.359Z
2024-09-20T12:08:54.364Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Received downstream response: tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_rpc=ApplyResourceChange @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 @module=sdk.proto tf_proto_version=5.3 tf_req_duration_ms=20493 tf_req_id=2437da30-d37f-8cab-3289-cbe1e195a4fd tf_resource_type=azuredevops_serviceendpoint_azurerm diagnostic_error_count=1 diagnostic_warning_count=0 timestamp=2024-09-20T12:08:54.359Z
2024-09-20T12:08:54.365Z [ERROR] provider.terraform-provider-azuredevops_v1.3.0: Response contains error diagnostic: @module=sdk.proto diagnostic_detail="" diagnostic_severity=ERROR diagnostic_summary=" waiting for service endpoint ready.  Looking up service endpoint given ID (bffcc32d-d0a8-4915-abed-e146a7d81124) and project ID (4ff74780-9ca2-4de5-8711-086114d194bb): map[errorCode:1 severity:<nil> state:Failed statusMessage:Failed to create an app in Microsoft Entra. Error: Insufficient privileges to complete the operation in Microsoft Graph Ensure that the user has permissions to create a Microsoft Entra Application.]  " tf_proto_version=5.3 @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/internal/diag/diagnostics.go:55 tf_req_id=2437da30-d37f-8cab-3289-cbe1e195a4fd tf_rpc=ApplyResourceChange tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_resource_type=azuredevops_serviceendpoint_azurerm timestamp=2024-09-20T12:08:54.360Z
2024-09-20T12:08:54.365Z [TRACE] provider.terraform-provider-azuredevops_v1.3.0: Served request: @caller=github.com/hashicorp/terraform-plugin-go@v0.14.0/tfprotov5/tf5server/server.go:831 tf_proto_version=5.3 tf_provider_addr=registry.terraform.io/microsoft/azuredevops tf_req_id=2437da30-d37f-8cab-3289-cbe1e195a4fd @module=sdk.proto tf_resource_type=azuredevops_serviceendpoint_azurerm tf_rpc=ApplyResourceChange timestamp=2024-09-20T12:08:54.360Z
2024-09-20T12:08:54.363Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:54.365Z [TRACE] states.SyncState: pruning module.service_connection because it is empty
2024-09-20T12:08:54.365Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:54.365Z [TRACE] evalApplyProvisioners: module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"] is tainted, so skipping provisioning
2024-09-20T12:08:54.365Z [TRACE] maybeTainted: module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"] was already tainted, so nothing to do
2024-09-20T12:08:54.366Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:54.366Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:54.366Z [TRACE] states.SyncState: pruning module.service_connection because it is empty
2024-09-20T12:08:54.366Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"]
2024-09-20T12:08:54.370Z [TRACE] statemgr.Filesystem: creating backup snapshot at terraform.tfstate.backup
2024-09-20T12:08:54.386Z [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 31
2024-09-20T12:08:54.387Z [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2024-09-20T12:08:54.391Z [DEBUG] State storage *statemgr.Filesystem declined to persist a state snapshot
2024-09-20T12:08:54.391Z [ERROR] vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]" error:  waiting for service endpoint ready.  Looking up service endpoint given ID (bffcc32d-d0a8-4915-abed-e146a7d81124) and project ID (4ff74780-9ca2-4de5-8711-086114d194bb): map[errorCode:1 severity:<nil> state:Failed statusMessage:Failed to create an app in Microsoft Entra. Error: Insufficient privileges to complete the operation in Microsoft Graph Ensure that the user has permissions to create a Microsoft Entra Application.]
2024-09-20T12:08:54.391Z [TRACE] vertex "module.service_connection.azuredevops_serviceendpoint_azurerm.this[\"ado_service_connnection\"]": visit complete, with errors
2024-09-20T12:08:54.391Z [TRACE] dag/walk: upstream of "module.service_connection.azuredevops_resource_authorization.this (expand)" errored, so skipping
2024-09-20T12:08:54.391Z [TRACE] dag/walk: upstream of "module.service_connection.azuredevops_resource_authorization.this[\"ado_service_connnection\"]" errored, so skipping
2024-09-20T12:08:54.391Z [TRACE] dag/walk: upstream of "module.service_connection (close)" errored, so skipping
2024-09-20T12:08:54.391Z [TRACE] dag/walk: upstream of "provider[\"registry.terraform.io/microsoft/azuredevops\"] (close)" errored, so skipping
2024-09-20T12:08:54.392Z [TRACE] dag/walk: upstream of "root" errored, so skipping
2024-09-20T12:08:54.392Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/microsoft/azuredevops" is in the global cache
2024-09-20T12:08:54.392Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/hashicorp/azurerm" is in the global cache
2024-09-20T12:08:54.392Z [TRACE] statemgr.Filesystem: have already backed up original terraform.tfstate to terraform.tfstate.backup on a previous write
2024-09-20T12:08:54.396Z [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 32
2024-09-20T12:08:54.396Z [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
╷
│ Error:  waiting for service endpoint ready.  Looking up service endpoint given ID (bffcc32d-d0a8-4915-abed-e146a7d81124) and project ID (4ff74780-9ca2-4de5-8711-086114d194bb): map[errorCode:1 severity:<nil> state:Failed statusMessage:Failed to create an app in Microsoft Entra. Error: Insufficient privileges to complete the operation in Microsoft Graph Ensure that the user has permissions to create a Microsoft Entra Application.]
│
│   with module.service_connection.azuredevops_serviceendpoint_azurerm.this["ado_service_connnection"],
│   on ../main.tf line 4, in resource "azuredevops_serviceendpoint_azurerm" "this":
│    4: resource "azuredevops_serviceendpoint_azurerm" "this" {
│
╵
2024-09-20T12:08:54.401Z [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2024-09-20T12:08:54.404Z [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
2024-09-20T12:08:54.426Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2024-09-20T12:08:54.429Z [INFO]  provider: plugin process exited: plugin=.terraform/providers/registry.terraform.io/microsoft/azuredevops/1.3.0/linux_amd64/terraform-provider-azuredevops_v1.3.0 id=1011
2024-09-20T12:08:54.429Z [DEBUG] provider: plugin exited