Skip to content

Instantly share code, notes, and snippets.

@prashantv
Created January 9, 2023 18:52
Show Gist options
  • Save prashantv/5e00eb5e6dc9215c280d7cb30a68cf26 to your computer and use it in GitHub Desktop.
Save prashantv/5e00eb5e6dc9215c280d7cb30a68cf26 to your computer and use it in GitHub Desktop.
terraform destroy triggers CustomizeDiff trace log
cd repro && CUSTOMIZE_DIFF_PANIC=1 terraform destroy
2023-01-09T10:52:39.474-0800 [INFO] Terraform version: 1.2.9
2023-01-09T10:52:39.474-0800 [DEBUG] using github.com/hashicorp/go-tfe v1.0.0
2023-01-09T10:52:39.474-0800 [DEBUG] using github.com/hashicorp/hcl/v2 v2.12.0
2023-01-09T10:52:39.474-0800 [DEBUG] using github.com/hashicorp/terraform-config-inspect v0.0.0-20210209133302-4fd17a0faac2
2023-01-09T10:52:39.474-0800 [DEBUG] using github.com/hashicorp/terraform-svchost v0.0.0-20200729002733-f050f53b9734
2023-01-09T10:52:39.474-0800 [DEBUG] using github.com/zclconf/go-cty v1.11.0
2023-01-09T10:52:39.474-0800 [INFO] Go runtime version: go1.18.1
2023-01-09T10:52:39.474-0800 [INFO] CLI args: []string{"terraform", "destroy"}
2023-01-09T10:52:39.474-0800 [TRACE] Stdout is not a terminal
2023-01-09T10:52:39.474-0800 [TRACE] Stderr is not a terminal
2023-01-09T10:52:39.474-0800 [TRACE] Stdin is a terminal
2023-01-09T10:52:39.474-0800 [DEBUG] Attempting to open CLI config file: /Users/prashant/.terraformrc
2023-01-09T10:52:39.474-0800 [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2023-01-09T10:52:39.474-0800 [DEBUG] checking for credentials in "/Users/prashant/.terraform.d/plugins"
2023-01-09T10:52:39.474-0800 [DEBUG] checking for credentials in "/Users/prashant/.terraform.d/plugins/darwin_arm64"
2023-01-09T10:52:39.474-0800 [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2023-01-09T10:52:39.474-0800 [DEBUG] will search for provider plugins in /Users/prashant/.terraform.d/plugins
2023-01-09T10:52:39.474-0800 [TRACE] getproviders.SearchLocalDirectory: found local/chronosphereio/chronosphere v0.0.1-concurrent for darwin_arm64 at /Users/prashant/.terraform.d/plugins/local/chronosphereio/chronosphere/0.0.1-concurrent/darwin_arm64
2023-01-09T10:52:39.474-0800 [TRACE] getproviders.SearchLocalDirectory: found local/chronosphereio/chronosphere v0.0.1-dev for darwin_arm64 at /Users/prashant/.terraform.d/plugins/local/chronosphereio/chronosphere/0.0.1-dev/darwin_arm64
2023-01-09T10:52:39.474-0800 [TRACE] getproviders.SearchLocalDirectory: found local/prashantv/tftest v0.1.0 for darwin_arm64 at /Users/prashant/.terraform.d/plugins/local/prashantv/tftest/0.1.0/darwin_arm64
2023-01-09T10:52:39.474-0800 [DEBUG] ignoring non-existing provider search directory /Users/prashant/Library/Application Support/io.terraform/plugins
2023-01-09T10:52:39.474-0800 [DEBUG] ignoring non-existing provider search directory /Library/Application Support/io.terraform/plugins
2023-01-09T10:52:39.474-0800 [INFO] CLI command args: []string{"destroy"}
2023-01-09T10:52:39.475-0800 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2023-01-09T10:52:39.475-0800 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2023-01-09T10:52:39.475-0800 [DEBUG] New state was assigned lineage "557bd2a8-858d-84e2-9fce-1b4f0693ac50"
2023-01-09T10:52:39.475-0800 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2023-01-09T10:52:39.475-0800 [TRACE] Meta.Backend: instantiated backend of type <nil>
2023-01-09T10:52:39.475-0800 [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2023-01-09T10:52:39.475-0800 [TRACE] getproviders.SearchLocalDirectory: found local/prashantv/tftest v0.1.0 for darwin_arm64 at .terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64
2023-01-09T10:52:39.475-0800 [TRACE] providercache.fillMetaCache: including .terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64 as a candidate package for local/prashantv/tftest 0.1.0
2023-01-09T10:52:39.485-0800 [DEBUG] checking for provisioner in "."
2023-01-09T10:52:39.485-0800 [DEBUG] checking for provisioner in "/Users/prashant/bin"
2023-01-09T10:52:39.485-0800 [DEBUG] checking for provisioner in "/Users/prashant/.terraform.d/plugins"
2023-01-09T10:52:39.485-0800 [DEBUG] checking for provisioner in "/Users/prashant/.terraform.d/plugins/darwin_arm64"
2023-01-09T10:52:39.485-0800 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2023-01-09T10:52:39.485-0800 [INFO] backend/local: starting Apply operation
2023-01-09T10:52:39.485-0800 [TRACE] backend/local: requesting state manager for workspace "default"
2023-01-09T10:52:39.485-0800 [TRACE] backend/local: state manager for workspace "default" will:
- read initial snapshot from terraform.tfstate
- write new snapshots to terraform.tfstate
- create any backup at terraform.tfstate.backup
2023-01-09T10:52:39.485-0800 [TRACE] backend/local: requesting state lock for workspace "default"
2023-01-09T10:52:39.485-0800 [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2023-01-09T10:52:39.485-0800 [TRACE] statemgr.Filesystem: existing snapshot has lineage "7aa2da2f-3286-f692-384e-b2f740f06528" serial 1
2023-01-09T10:52:39.485-0800 [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2023-01-09T10:52:39.485-0800 [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2023-01-09T10:52:39.485-0800 [TRACE] backend/local: reading remote state for workspace "default"
2023-01-09T10:52:39.485-0800 [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2023-01-09T10:52:39.485-0800 [TRACE] statemgr.Filesystem: read snapshot with lineage "7aa2da2f-3286-f692-384e-b2f740f06528" serial 1
2023-01-09T10:52:39.485-0800 [TRACE] backend/local: populating backend.LocalRun for current working directory
2023-01-09T10:52:39.485-0800 [TRACE] Config.VerifyDependencySelections: provider local/prashantv/tftest has 0.1.0 to satisfy "0.1.0"
2023-01-09T10:52:39.485-0800 [TRACE] terraform.NewContext: starting
2023-01-09T10:52:39.485-0800 [TRACE] terraform.NewContext: complete
2023-01-09T10:52:39.485-0800 [TRACE] backend/local: requesting interactive input, if necessary
2023-01-09T10:52:39.485-0800 [TRACE] LoadSchemas: retrieving schema for provider type "local/prashantv/tftest"
2023-01-09T10:52:39.485-0800 [TRACE] terraform.contextPlugins: Initializing provider "local/prashantv/tftest" to read its schema
2023-01-09T10:52:39.485-0800 [DEBUG] created provider logger: level=trace
2023-01-09T10:52:39.485-0800 [INFO] provider: configuring client automatic mTLS
2023-01-09T10:52:39.493-0800 [DEBUG] provider: starting plugin: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest args=[.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest]
2023-01-09T10:52:39.495-0800 [DEBUG] provider: plugin started: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest pid=28837
2023-01-09T10:52:39.495-0800 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest
2023-01-09T10:52:39.499-0800 [INFO] provider.terraform-provider-tftest: configuring server automatic mTLS: timestamp=2023-01-09T10:52:39.499-0800
2023-01-09T10:52:39.507-0800 [DEBUG] provider.terraform-provider-tftest: plugin address: address=/var/folders/2f/kn4pgn0x5dv1nqm97qh06sch0000gp/T/plugin2232399434 network=unix timestamp=2023-01-09T10:52:39.507-0800
2023-01-09T10:52:39.507-0800 [DEBUG] provider: using plugin: version=5
2023-01-09T10:52:39.518-0800 [TRACE] GRPCProvider: GetProviderSchema
2023-01-09T10:52:39.518-0800 [TRACE] provider.stdio: waiting for stdio data
2023-01-09T10:52:39.518-0800 [TRACE] provider.terraform-provider-tftest: Received request: tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:491 @module=sdk.proto tf_req_id=084cd618-73bf-9abe-a595-387321374509 timestamp=2023-01-09T10:52:39.518-0800
2023-01-09T10:52:39.518-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: tf_req_id=084cd618-73bf-9abe-a595-387321374509 tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider timestamp=2023-01-09T10:52:39.518-0800
2023-01-09T10:52:39.518-0800 [TRACE] provider.terraform-provider-tftest: Getting provider schema: tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:70 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=084cd618-73bf-9abe-a595-387321374509 timestamp=2023-01-09T10:52:39.518-0800
2023-01-09T10:52:39.518-0800 [TRACE] provider.terraform-provider-tftest: Found resource type: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:86 tf_resource_type=tftest_dummy tf_rpc=GetProviderSchema @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=084cd618-73bf-9abe-a595-387321374509 timestamp=2023-01-09T10:52:39.518-0800
2023-01-09T10:52:39.518-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: @module=sdk.proto diagnostic_error_count=0 diagnostic_warning_count=0 tf_provider_addr=provider tf_req_duration_ms=0 tf_proto_version=5.3 tf_req_id=084cd618-73bf-9abe-a595-387321374509 tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 timestamp=2023-01-09T10:52:39.518-0800
2023-01-09T10:52:39.518-0800 [TRACE] provider.terraform-provider-tftest: Served request: @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=084cd618-73bf-9abe-a595-387321374509 tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:510 timestamp=2023-01-09T10:52:39.518-0800
2023-01-09T10:52:39.519-0800 [TRACE] GRPCProvider: Close
2023-01-09T10:52:39.519-0800 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2023-01-09T10:52:39.519-0800 [DEBUG] provider: plugin process exited: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest pid=28837
2023-01-09T10:52:39.519-0800 [DEBUG] provider: plugin exited
2023-01-09T10:52:39.519-0800 [TRACE] Context.Input: Prompting for provider arguments
2023-01-09T10:52:39.519-0800 [TRACE] Context.Input: Provider provider.tftest implied by resource block at main.tf:10,1-28
2023-01-09T10:52:39.519-0800 [TRACE] Context.Input: Input for provider.tftest: map[string]cty.Value{}
2023-01-09T10:52:39.519-0800 [TRACE] backend/local: running validation operation
2023-01-09T10:52:39.519-0800 [DEBUG] Building and walking validate graph
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.ConfigTransformer
2023-01-09T10:52:39.519-0800 [TRACE] ConfigTransformer: Starting for path:
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
tftest_dummy.d - *terraform.NodeValidatableResource
------
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.LocalTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.OutputTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.StateTransformer
2023-01-09T10:52:39.519-0800 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2023-01-09T10:52:39.519-0800 [TRACE] AttachResourceConfigTransformer: attaching to "tftest_dummy.d" (*terraform.NodeValidatableResource) config from main.tf:10,1-28
2023-01-09T10:52:39.519-0800 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to tftest_dummy.d
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2023-01-09T10:52:39.519-0800 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2023-01-09T10:52:39.519-0800 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d - *terraform.NodeValidatableResource
------
2023-01-09T10:52:39.519-0800 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2023-01-09T10:52:39.519-0800 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2023-01-09T10:52:39.519-0800 [TRACE] ProviderTransformer: exact match for provider["local/prashantv/tftest"] serving tftest_dummy.d
2023-01-09T10:52:39.519-0800 [DEBUG] ProviderTransformer: "tftest_dummy.d" (*terraform.NodeValidatableResource) needs provider["local/prashantv/tftest"]
2023-01-09T10:52:39.519-0800 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d - *terraform.NodeValidatableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.519-0800 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2023-01-09T10:52:39.519-0800 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d - *terraform.NodeValidatableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2023-01-09T10:52:39.519-0800 [TRACE] AttachSchemaTransformer: attaching resource schema to tftest_dummy.d
2023-01-09T10:52:39.519-0800 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["local/prashantv/tftest"]
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2023-01-09T10:52:39.519-0800 [DEBUG] ReferenceTransformer: "tftest_dummy.d" references: []
2023-01-09T10:52:39.519-0800 [DEBUG] ReferenceTransformer: "provider[\"local/prashantv/tftest\"]" references: []
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.TargetsTransformer
2023-01-09T10:52:39.519-0800 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2023-01-09T10:52:39.519-0800 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2023-01-09T10:52:39.520-0800 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2023-01-09T10:52:39.520-0800 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2023-01-09T10:52:39.520-0800 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d - *terraform.NodeValidatableResource
tftest_dummy.d - *terraform.NodeValidatableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.520-0800 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2023-01-09T10:52:39.520-0800 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d - *terraform.NodeValidatableResource
root - *terraform.nodeCloseModule
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
tftest_dummy.d - *terraform.NodeValidatableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.520-0800 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2023-01-09T10:52:39.520-0800 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
tftest_dummy.d - *terraform.NodeValidatableResource
root - *terraform.nodeCloseModule
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
tftest_dummy.d - *terraform.NodeValidatableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.520-0800 [DEBUG] Starting graph walk: walkValidate
2023-01-09T10:52:39.520-0800 [TRACE] vertex "provider[\"local/prashantv/tftest\"]": starting visit (*terraform.NodeApplyableProvider)
2023-01-09T10:52:39.520-0800 [DEBUG] created provider logger: level=trace
2023-01-09T10:52:39.520-0800 [INFO] provider: configuring client automatic mTLS
2023-01-09T10:52:39.528-0800 [DEBUG] provider: starting plugin: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest args=[.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest]
2023-01-09T10:52:39.529-0800 [DEBUG] provider: plugin started: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest pid=28838
2023-01-09T10:52:39.529-0800 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest
2023-01-09T10:52:39.533-0800 [INFO] provider.terraform-provider-tftest: configuring server automatic mTLS: timestamp=2023-01-09T10:52:39.533-0800
2023-01-09T10:52:39.541-0800 [DEBUG] provider: using plugin: version=5
2023-01-09T10:52:39.541-0800 [DEBUG] provider.terraform-provider-tftest: plugin address: address=/var/folders/2f/kn4pgn0x5dv1nqm97qh06sch0000gp/T/plugin2454451675 network=unix timestamp=2023-01-09T10:52:39.541-0800
2023-01-09T10:52:39.551-0800 [TRACE] BuiltinEvalContext: Initialized "provider[\"local/prashantv/tftest\"]" provider for provider["local/prashantv/tftest"]
2023-01-09T10:52:39.551-0800 [TRACE] NodeApplyableProvider: validating configuration for provider["local/prashantv/tftest"]
2023-01-09T10:52:39.551-0800 [TRACE] buildProviderConfig for provider["local/prashantv/tftest"]: no configuration at all
2023-01-09T10:52:39.551-0800 [TRACE] vertex "provider[\"local/prashantv/tftest\"]": visit complete
2023-01-09T10:52:39.551-0800 [TRACE] provider.stdio: waiting for stdio data
2023-01-09T10:52:39.551-0800 [TRACE] vertex "tftest_dummy.d": starting visit (*terraform.NodeValidatableResource)
2023-01-09T10:52:39.551-0800 [TRACE] GRPCProvider: ValidateResourceConfig
2023-01-09T10:52:39.551-0800 [TRACE] GRPCProvider: GetProviderSchema
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Received request: @module=sdk.proto tf_proto_version=5.3 tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:491 tf_provider_addr=provider tf_req_id=0e854522-ec50-d823-855f-30b5fd02b64f timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: tf_provider_addr=provider tf_req_id=0e854522-ec50-d823-855f-30b5fd02b64f tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Getting provider schema: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=0e854522-ec50-d823-855f-30b5fd02b64f tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:70 timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Found resource type: tf_req_id=0e854522-ec50-d823-855f-30b5fd02b64f tf_rpc=GetProviderSchema @module=sdk.helper_schema tf_provider_addr=provider tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:86 timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: tf_proto_version=5.3 tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 @module=sdk.proto diagnostic_error_count=0 diagnostic_warning_count=0 tf_provider_addr=provider tf_req_duration_ms=0 tf_req_id=0e854522-ec50-d823-855f-30b5fd02b64f timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Served request: tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=GetProviderSchema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:510 @module=sdk.proto tf_req_id=0e854522-ec50-d823-855f-30b5fd02b64f timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Received request: tf_req_id=f5bcbf49-ee22-3a21-524b-d6a93481450e tf_rpc=ValidateResourceTypeConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:679 tf_proto_version=5.3 @module=sdk.proto tf_provider_addr=provider tf_resource_type=tftest_dummy timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Skipping protocol data file writing because no data directory is set. Use the TF_LOG_SDK_PROTO_DATA_DIR environment variable to enable this functionality.: tf_provider_addr=provider tf_req_id=f5bcbf49-ee22-3a21-524b-d6a93481450e tf_rpc=ValidateResourceTypeConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/internal/logging/protocol_data.go:38 tf_proto_version=5.3 tf_resource_type=tftest_dummy @module=sdk.proto timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: tf_rpc=ValidateResourceTypeConfig tf_proto_version=5.3 tf_req_id=f5bcbf49-ee22-3a21-524b-d6a93481450e tf_resource_type=tftest_dummy tf_provider_addr=provider @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Calling downstream: tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:245 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=f5bcbf49-ee22-3a21-524b-d6a93481450e tf_rpc=ValidateResourceTypeConfig timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Called downstream: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:247 tf_req_id=f5bcbf49-ee22-3a21-524b-d6a93481450e tf_resource_type=tftest_dummy tf_rpc=ValidateResourceTypeConfig @module=sdk.helper_schema tf_provider_addr=provider timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: diagnostic_error_count=0 diagnostic_warning_count=0 tf_proto_version=5.3 tf_req_duration_ms=0 tf_req_id=f5bcbf49-ee22-3a21-524b-d6a93481450e tf_resource_type=tftest_dummy @module=sdk.proto tf_provider_addr=provider tf_rpc=ValidateResourceTypeConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] provider.terraform-provider-tftest: Served request: tf_provider_addr=provider @module=sdk.proto tf_proto_version=5.3 tf_req_id=f5bcbf49-ee22-3a21-524b-d6a93481450e tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:699 tf_rpc=ValidateResourceTypeConfig timestamp=2023-01-09T10:52:39.552-0800
2023-01-09T10:52:39.552-0800 [TRACE] vertex "tftest_dummy.d": visit complete
2023-01-09T10:52:39.552-0800 [TRACE] vertex "provider[\"local/prashantv/tftest\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2023-01-09T10:52:39.552-0800 [TRACE] GRPCProvider: Close
2023-01-09T10:52:39.553-0800 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2023-01-09T10:52:39.553-0800 [DEBUG] provider: plugin process exited: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest pid=28838
2023-01-09T10:52:39.553-0800 [DEBUG] provider: plugin exited
2023-01-09T10:52:39.553-0800 [TRACE] vertex "provider[\"local/prashantv/tftest\"] (close)": visit complete
2023-01-09T10:52:39.553-0800 [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2023-01-09T10:52:39.553-0800 [TRACE] vertex "root": visit complete
2023-01-09T10:52:39.553-0800 [INFO] backend/local: apply calling Plan
2023-01-09T10:52:39.553-0800 [TRACE] Context.destroyPlan: calling Context.plan to get the effect of refreshing the prior state
2023-01-09T10:52:39.553-0800 [DEBUG] Building and walking plan graph for NormalMode
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.ConfigTransformer
2023-01-09T10:52:39.553-0800 [TRACE] ConfigTransformer: Starting for path:
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
------
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.LocalTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.OutputTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.StateTransformer
2023-01-09T10:52:39.553-0800 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2023-01-09T10:52:39.553-0800 [TRACE] AttachResourceConfigTransformer: attaching to "tftest_dummy.d (expand)" (*terraform.nodeExpandPlannableResource) config from main.tf:10,1-28
2023-01-09T10:52:39.553-0800 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to tftest_dummy.d (expand)
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2023-01-09T10:52:39.553-0800 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2023-01-09T10:52:39.553-0800 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
------
2023-01-09T10:52:39.553-0800 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2023-01-09T10:52:39.553-0800 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2023-01-09T10:52:39.553-0800 [TRACE] ProviderTransformer: exact match for provider["local/prashantv/tftest"] serving tftest_dummy.d (expand)
2023-01-09T10:52:39.553-0800 [DEBUG] ProviderTransformer: "tftest_dummy.d (expand)" (*terraform.nodeExpandPlannableResource) needs provider["local/prashantv/tftest"]
2023-01-09T10:52:39.553-0800 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.553-0800 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2023-01-09T10:52:39.553-0800 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2023-01-09T10:52:39.553-0800 [TRACE] AttachSchemaTransformer: attaching resource schema to tftest_dummy.d (expand)
2023-01-09T10:52:39.553-0800 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["local/prashantv/tftest"]
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2023-01-09T10:52:39.553-0800 [DEBUG] ReferenceTransformer: "tftest_dummy.d (expand)" references: []
2023-01-09T10:52:39.553-0800 [DEBUG] ReferenceTransformer: "provider[\"local/prashantv/tftest\"]" references: []
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2023-01-09T10:52:39.553-0800 [TRACE] AttachDependenciesTransformer: tftest_dummy.d depends on []
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.TargetsTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2023-01-09T10:52:39.553-0800 [TRACE] ForcedCBDTransformer: "tftest_dummy.d (expand)" (*terraform.nodeExpandPlannableResource) has no CBD descendent, so skipping
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
root - *terraform.nodeCloseModule
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.553-0800 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2023-01-09T10:52:39.553-0800 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
root - *terraform.nodeCloseModule
provider["local/prashantv/tftest"] (close) - *terraform.graphNodeCloseProvider
tftest_dummy.d (expand) - *terraform.nodeExpandPlannableResource
provider["local/prashantv/tftest"] - *terraform.NodeApplyableProvider
------
2023-01-09T10:52:39.553-0800 [DEBUG] Starting graph walk: walkPlan
2023-01-09T10:52:39.553-0800 [TRACE] vertex "provider[\"local/prashantv/tftest\"]": starting visit (*terraform.NodeApplyableProvider)
2023-01-09T10:52:39.553-0800 [DEBUG] created provider logger: level=trace
2023-01-09T10:52:39.553-0800 [INFO] provider: configuring client automatic mTLS
2023-01-09T10:52:39.561-0800 [DEBUG] provider: starting plugin: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest args=[.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest]
2023-01-09T10:52:39.562-0800 [DEBUG] provider: plugin started: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest pid=28839
2023-01-09T10:52:39.563-0800 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest
2023-01-09T10:52:39.567-0800 [INFO] provider.terraform-provider-tftest: configuring server automatic mTLS: timestamp=2023-01-09T10:52:39.567-0800
2023-01-09T10:52:39.575-0800 [DEBUG] provider: using plugin: version=5
2023-01-09T10:52:39.575-0800 [DEBUG] provider.terraform-provider-tftest: plugin address: address=/var/folders/2f/kn4pgn0x5dv1nqm97qh06sch0000gp/T/plugin1718897421 network=unix timestamp=2023-01-09T10:52:39.575-0800
2023-01-09T10:52:39.585-0800 [TRACE] BuiltinEvalContext: Initialized "provider[\"local/prashantv/tftest\"]" provider for provider["local/prashantv/tftest"]
2023-01-09T10:52:39.585-0800 [TRACE] NodeApplyableProvider: configuring provider["local/prashantv/tftest"]
2023-01-09T10:52:39.585-0800 [TRACE] buildProviderConfig for provider["local/prashantv/tftest"]: no configuration at all
2023-01-09T10:52:39.585-0800 [TRACE] GRPCProvider: GetProviderSchema
2023-01-09T10:52:39.585-0800 [TRACE] provider.stdio: waiting for stdio data
2023-01-09T10:52:39.585-0800 [TRACE] provider.terraform-provider-tftest: Received request: @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:491 tf_req_id=da7db522-27bf-5777-3c11-104e16dbd0d9 tf_rpc=GetProviderSchema timestamp=2023-01-09T10:52:39.585-0800
2023-01-09T10:52:39.585-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: tf_req_id=da7db522-27bf-5777-3c11-104e16dbd0d9 @module=sdk.proto tf_proto_version=5.3 @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 tf_provider_addr=provider tf_rpc=GetProviderSchema timestamp=2023-01-09T10:52:39.585-0800
2023-01-09T10:52:39.585-0800 [TRACE] provider.terraform-provider-tftest: Getting provider schema: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:70 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=da7db522-27bf-5777-3c11-104e16dbd0d9 tf_rpc=GetProviderSchema timestamp=2023-01-09T10:52:39.585-0800
2023-01-09T10:52:39.585-0800 [TRACE] provider.terraform-provider-tftest: Found resource type: @module=sdk.helper_schema tf_rpc=GetProviderSchema tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:86 tf_provider_addr=provider tf_req_id=da7db522-27bf-5777-3c11-104e16dbd0d9 timestamp=2023-01-09T10:52:39.585-0800
2023-01-09T10:52:39.585-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: tf_req_duration_ms=0 tf_rpc=GetProviderSchema @module=sdk.proto tf_provider_addr=provider diagnostic_error_count=0 diagnostic_warning_count=0 tf_proto_version=5.3 tf_req_id=da7db522-27bf-5777-3c11-104e16dbd0d9 @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 timestamp=2023-01-09T10:52:39.585-0800
2023-01-09T10:52:39.585-0800 [TRACE] provider.terraform-provider-tftest: Served request: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:510 @module=sdk.proto tf_provider_addr=provider tf_rpc=GetProviderSchema tf_proto_version=5.3 tf_req_id=da7db522-27bf-5777-3c11-104e16dbd0d9 timestamp=2023-01-09T10:52:39.585-0800
2023-01-09T10:52:39.586-0800 [TRACE] GRPCProvider: ValidateProviderConfig
2023-01-09T10:52:39.586-0800 [TRACE] provider.terraform-provider-tftest: Received request: tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=PrepareProviderConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:518 tf_req_id=1c6eba89-063c-08fc-a9bb-27dffb1178c7 @module=sdk.proto timestamp=2023-01-09T10:52:39.586-0800
2023-01-09T10:52:39.586-0800 [TRACE] provider.terraform-provider-tftest: Skipping protocol data file writing because no data directory is set. Use the TF_LOG_SDK_PROTO_DATA_DIR environment variable to enable this functionality.: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/internal/logging/protocol_data.go:38 tf_provider_addr=provider tf_rpc=PrepareProviderConfig @module=sdk.proto tf_proto_version=5.3 tf_req_id=1c6eba89-063c-08fc-a9bb-27dffb1178c7 timestamp=2023-01-09T10:52:39.586-0800
2023-01-09T10:52:39.586-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_rpc=PrepareProviderConfig tf_provider_addr=provider tf_req_id=1c6eba89-063c-08fc-a9bb-27dffb1178c7 timestamp=2023-01-09T10:52:39.586-0800
2023-01-09T10:52:39.586-0800 [TRACE] provider.terraform-provider-tftest: Preparing provider configuration: tf_provider_addr=provider tf_req_id=1c6eba89-063c-08fc-a9bb-27dffb1178c7 tf_rpc=PrepareProviderConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:128 @module=sdk.helper_schema timestamp=2023-01-09T10:52:39.586-0800
2023-01-09T10:52:39.586-0800 [TRACE] provider.terraform-provider-tftest: Calling downstream: tf_rpc=PrepareProviderConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:216 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=1c6eba89-063c-08fc-a9bb-27dffb1178c7 timestamp=2023-01-09T10:52:39.586-0800
2023-01-09T10:52:39.586-0800 [TRACE] provider.terraform-provider-tftest: Called downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=1c6eba89-063c-08fc-a9bb-27dffb1178c7 tf_rpc=PrepareProviderConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:218 timestamp=2023-01-09T10:52:39.586-0800
2023-01-09T10:52:39.586-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: tf_provider_addr=provider tf_req_duration_ms=0 tf_req_id=1c6eba89-063c-08fc-a9bb-27dffb1178c7 @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 tf_proto_version=5.3 diagnostic_error_count=0 diagnostic_warning_count=0 tf_rpc=PrepareProviderConfig @module=sdk.proto timestamp=2023-01-09T10:52:39.586-0800
2023-01-09T10:52:39.586-0800 [TRACE] provider.terraform-provider-tftest: Served request: tf_proto_version=5.3 tf_provider_addr=provider @module=sdk.proto tf_req_id=1c6eba89-063c-08fc-a9bb-27dffb1178c7 tf_rpc=PrepareProviderConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:539 timestamp=2023-01-09T10:52:39.586-0800
2023-01-09T10:52:39.586-0800 [TRACE] GRPCProvider: ConfigureProvider
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Received request: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:547 tf_provider_addr=provider tf_rpc=Configure @module=sdk.proto tf_proto_version=5.3 tf_req_id=7ec9b60b-4084-5e2d-a2ca-ccef3e501445 timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=7ec9b60b-4084-5e2d-a2ca-ccef3e501445 @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_rpc=Configure timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Calling downstream: tf_provider_addr=provider tf_req_id=7ec9b60b-4084-5e2d-a2ca-ccef3e501445 tf_rpc=Configure @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:556 @module=sdk.helper_schema timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Called downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=7ec9b60b-4084-5e2d-a2ca-ccef3e501445 tf_rpc=Configure @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:558 timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: tf_proto_version=5.3 tf_rpc=Configure @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 @module=sdk.proto diagnostic_error_count=0 diagnostic_warning_count=0 tf_provider_addr=provider tf_req_duration_ms=0 tf_req_id=7ec9b60b-4084-5e2d-a2ca-ccef3e501445 timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Served request: tf_proto_version=5.3 tf_req_id=7ec9b60b-4084-5e2d-a2ca-ccef3e501445 @module=sdk.proto tf_provider_addr=provider tf_rpc=Configure @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:567 timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] vertex "provider[\"local/prashantv/tftest\"]": visit complete
2023-01-09T10:52:39.587-0800 [TRACE] vertex "tftest_dummy.d (expand)": starting visit (*terraform.nodeExpandPlannableResource)
2023-01-09T10:52:39.587-0800 [TRACE] vertex "tftest_dummy.d (expand)": expanding dynamic subgraph
2023-01-09T10:52:39.587-0800 [TRACE] vertex "tftest_dummy.d (expand)": entering dynamic subgraph
2023-01-09T10:52:39.587-0800 [TRACE] vertex "tftest_dummy.d": starting visit (*terraform.NodePlannableResource)
2023-01-09T10:52:39.587-0800 [TRACE] vertex "tftest_dummy.d": expanding dynamic subgraph
2023-01-09T10:52:39.587-0800 [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2023-01-09T10:52:39.587-0800 [TRACE] ResourceCountTransformer: adding tftest_dummy.d as *terraform.NodePlannableResourceInstance
2023-01-09T10:52:39.587-0800 [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
tftest_dummy.d - *terraform.NodePlannableResourceInstance
------
2023-01-09T10:52:39.587-0800 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceCountTransformer
2023-01-09T10:52:39.587-0800 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceCountTransformer (no changes)
2023-01-09T10:52:39.587-0800 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2023-01-09T10:52:39.587-0800 [TRACE] NodeAbstractResourceInstance.AttachResourceState for tftest_dummy.d
2023-01-09T10:52:39.587-0800 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2023-01-09T10:52:39.587-0800 [TRACE] Executing graph transform *terraform.TargetsTransformer
2023-01-09T10:52:39.587-0800 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2023-01-09T10:52:39.587-0800 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2023-01-09T10:52:39.587-0800 [DEBUG] ReferenceTransformer: "tftest_dummy.d" references: []
2023-01-09T10:52:39.587-0800 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2023-01-09T10:52:39.587-0800 [TRACE] Executing graph transform *terraform.RootTransformer
2023-01-09T10:52:39.587-0800 [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2023-01-09T10:52:39.587-0800 [TRACE] vertex "tftest_dummy.d": entering dynamic subgraph
2023-01-09T10:52:39.587-0800 [TRACE] vertex "tftest_dummy.d": starting visit (*terraform.NodePlannableResourceInstance)
2023-01-09T10:52:39.587-0800 [TRACE] readResourceInstanceState: reading state for tftest_dummy.d
2023-01-09T10:52:39.587-0800 [TRACE] upgradeResourceState: schema version of tftest_dummy.d is still 0; calling provider "tftest" for any other minor fixups
2023-01-09T10:52:39.587-0800 [TRACE] GRPCProvider: UpgradeResourceState
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Received request: tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=9a9e7a10-40a6-507e-be2a-a6a306d2f6a1 tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:708 @module=sdk.proto tf_rpc=UpgradeResourceState timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=9a9e7a10-40a6-507e-be2a-a6a306d2f6a1 tf_resource_type=tftest_dummy tf_rpc=UpgradeResourceState timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Upgrading JSON state: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:323 @module=sdk.helper_schema tf_resource_type=tftest_dummy tf_rpc=UpgradeResourceState tf_provider_addr=provider tf_req_id=9a9e7a10-40a6-507e-be2a-a6a306d2f6a1 timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: diagnostic_warning_count=0 tf_req_duration_ms=0 tf_rpc=UpgradeResourceState @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 @module=sdk.proto diagnostic_error_count=0 tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=9a9e7a10-40a6-507e-be2a-a6a306d2f6a1 tf_resource_type=tftest_dummy timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Served request: @module=sdk.proto tf_req_id=9a9e7a10-40a6-507e-be2a-a6a306d2f6a1 tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:728 tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=UpgradeResourceState timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to prevRunState for tftest_dummy.d
2023-01-09T10:52:39.587-0800 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for tftest_dummy.d
2023-01-09T10:52:39.587-0800 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for tftest_dummy.d
2023-01-09T10:52:39.587-0800 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for tftest_dummy.d
2023-01-09T10:52:39.587-0800 [TRACE] NodeAbstractResourceInstance.refresh for tftest_dummy.d
tftest_dummy.d: Refreshing state... [id=created]
2023-01-09T10:52:39.587-0800 [TRACE] GRPCProvider: ReadResource
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Received request: tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=de25f8fb-5239-87c6-c6fc-bc42b2d119da tf_rpc=ReadResource @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:737 @module=sdk.proto tf_resource_type=tftest_dummy timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.587-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: tf_provider_addr=provider tf_resource_type=tftest_dummy tf_rpc=ReadResource @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_req_id=de25f8fb-5239-87c6-c6fc-bc42b2d119da timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Calling downstream: @module=sdk.helper_schema @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/resource.go:1014 tf_provider_addr=provider tf_req_id=de25f8fb-5239-87c6-c6fc-bc42b2d119da tf_resource_type=tftest_dummy tf_rpc=ReadResource timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Called downstream: tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/resource.go:1016 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=de25f8fb-5239-87c6-c6fc-bc42b2d119da tf_rpc=ReadResource timestamp=2023-01-09T10:52:39.587-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: @module=sdk.proto tf_req_duration_ms=0 tf_req_id=de25f8fb-5239-87c6-c6fc-bc42b2d119da tf_rpc=ReadResource tf_proto_version=5.3 tf_provider_addr=provider @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 diagnostic_error_count=0 diagnostic_warning_count=0 tf_resource_type=tftest_dummy timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Served request: @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=de25f8fb-5239-87c6-c6fc-bc42b2d119da tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:761 tf_rpc=ReadResource timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for tftest_dummy.d
2023-01-09T10:52:39.588-0800 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for tftest_dummy.d
2023-01-09T10:52:39.588-0800 [TRACE] Re-validating config for "tftest_dummy.d"
2023-01-09T10:52:39.588-0800 [TRACE] GRPCProvider: ValidateResourceConfig
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Received request: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:679 tf_proto_version=5.3 @module=sdk.proto tf_provider_addr=provider tf_req_id=292e5823-5f2d-fc97-c949-df1b5e81fb5b tf_resource_type=tftest_dummy tf_rpc=ValidateResourceTypeConfig timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=292e5823-5f2d-fc97-c949-df1b5e81fb5b tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_rpc=ValidateResourceTypeConfig timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Calling downstream: tf_rpc=ValidateResourceTypeConfig @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:245 tf_req_id=292e5823-5f2d-fc97-c949-df1b5e81fb5b tf_resource_type=tftest_dummy tf_provider_addr=provider @module=sdk.helper_schema timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Called downstream: @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:247 tf_provider_addr=provider tf_req_id=292e5823-5f2d-fc97-c949-df1b5e81fb5b tf_resource_type=tftest_dummy tf_rpc=ValidateResourceTypeConfig @module=sdk.helper_schema timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Received downstream response: diagnostic_error_count=0 diagnostic_warning_count=0 tf_proto_version=5.3 tf_provider_addr=provider tf_req_duration_ms=0 @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 tf_resource_type=tftest_dummy tf_rpc=ValidateResourceTypeConfig @module=sdk.proto tf_req_id=292e5823-5f2d-fc97-c949-df1b5e81fb5b timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Served request: tf_rpc=ValidateResourceTypeConfig @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=292e5823-5f2d-fc97-c949-df1b5e81fb5b tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:699 timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] GRPCProvider: PlanResourceChange
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Received request: tf_resource_type=tftest_dummy @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:770 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=6148c081-25fb-f048-84b7-996d20d72d91 tf_rpc=PlanResourceChange timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Sending request downstream: tf_provider_addr=provider tf_resource_type=tftest_dummy tf_rpc=PlanResourceChange @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_req_id=6148c081-25fb-f048-84b7-996d20d72d91 timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Calling downstream: @module=sdk.helper_schema tf_req_id=6148c081-25fb-f048-84b7-996d20d72d91 tf_rpc=PlanResourceChange @caller=/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/schema.go:698 tf_provider_addr=provider tf_resource_type=tftest_dummy timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.588-0800 [TRACE] provider.terraform-provider-tftest: Served request: tf_req_id=6148c081-25fb-f048-84b7-996d20d72d91 tf_resource_type=tftest_dummy @caller=/Users/prashant/.gimme/versions/go1.18.3.darwin.arm64/src/runtime/panic.go:838 tf_provider_addr=provider tf_proto_version=5.3 tf_rpc=PlanResourceChange @module=sdk.proto timestamp=2023-01-09T10:52:39.588-0800
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: panic: customize_diff called with CUSTOMIZE_DIFF_PANIC=1
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest:
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: goroutine 24 [running]:
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: github.com/prashantv/tf-test/internal/provider.dummyResource.func1({0x10096ebe0?, 0x14000184150?}, 0x10070203c?, {0xd?, 0x100704dfd?})
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/dev/3p/tf-bugs/terraform-customizediff-on-destroy-repro/internal/provider/dummy_resource.go:26 +0x58
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: github.com/hashicorp/terraform-plugin-sdk/v2/helper/schema.schemaMap.Diff(0x140003d30e0, {0x10096ebe0, 0x14000184150}, 0x14000434750, 0x14000184d50, 0x100964c80, {0x0, 0x0}, 0x0)
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/schema.go:699 +0x3c4
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: github.com/hashicorp/terraform-plugin-sdk/v2/helper/schema.(*Resource).SimpleDiff(0x10096f2e0?, {0x10096ebe0?, 0x14000184150?}, 0x14000434750, 0x10089fe20?, {0x0?, 0x0?})
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/resource.go:890 +0x50
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: github.com/hashicorp/terraform-plugin-sdk/v2/helper/schema.(*GRPCProviderServer).PlanResourceChange(0x14000121848, {0x10096ebe0?, 0x14000184030?}, 0x1400041a3c0)
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:741 +0x85c
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: github.com/hashicorp/terraform-plugin-go/tfprotov5/tf5server.(*server).PlanResourceChange(0x14000128c80, {0x10096ebe0?, 0x140001ca390?}, 0x140000d80e0)
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:783 +0x3c0
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: github.com/hashicorp/terraform-plugin-go/tfprotov5/internal/tfplugin5._Provider_PlanResourceChange_Handler({0x10093e5e0?, 0x14000128c80}, {0x10096ebe0, 0x140001ca390}, 0x140000d8070, 0x0)
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tfplugin5/tfplugin5_grpc.pb.go:367 +0x174
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: google.golang.org/grpc.(*Server).processUnaryRPC(0x140003e2000, {0x100971700, 0x1400029a1a0}, 0x140003f8000, 0x140003d3200, 0x100db24c8, 0x0)
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/google.golang.org/grpc@v1.51.0/server.go:1340 +0xb90
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: google.golang.org/grpc.(*Server).handleStream(0x140003e2000, {0x100971700, 0x1400029a1a0}, 0x140003f8000, 0x0)
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/google.golang.org/grpc@v1.51.0/server.go:1713 +0x840
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: google.golang.org/grpc.(*Server).serveStreams.func1.2()
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/google.golang.org/grpc@v1.51.0/server.go:965 +0x88
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: created by google.golang.org/grpc.(*Server).serveStreams.func1
2023-01-09T10:52:39.591-0800 [DEBUG] provider.terraform-provider-tftest: /Users/prashant/go/pkg/mod/google.golang.org/grpc@v1.51.0/server.go:963 +0x298
2023-01-09T10:52:39.591-0800 [DEBUG] provider: plugin process exited: path=.terraform/providers/local/prashantv/tftest/0.1.0/darwin_arm64/terraform-provider-tftest pid=28839 error="exit status 2"
2023-01-09T10:52:39.591-0800 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Canceled desc = context canceled"
2023-01-09T10:52:39.591-0800 [ERROR] plugin.(*GRPCProvider).PlanResourceChange: error="rpc error: code = Unavailable desc = transport is closing"
2023-01-09T10:52:39.591-0800 [ERROR] vertex "tftest_dummy.d" error: Plugin did not respond
2023-01-09T10:52:39.591-0800 [TRACE] vertex "tftest_dummy.d": visit complete, with errors
2023-01-09T10:52:39.591-0800 [TRACE] vertex "tftest_dummy.d": dynamic subgraph encountered errors: Plugin did not respond
2023-01-09T10:52:39.591-0800 [ERROR] vertex "tftest_dummy.d" error: Plugin did not respond
2023-01-09T10:52:39.591-0800 [TRACE] vertex "tftest_dummy.d": visit complete, with errors
2023-01-09T10:52:39.591-0800 [TRACE] vertex "tftest_dummy.d (expand)": dynamic subgraph encountered errors: Plugin did not respond
2023-01-09T10:52:39.591-0800 [ERROR] vertex "tftest_dummy.d (expand)" error: Plugin did not respond
2023-01-09T10:52:39.591-0800 [TRACE] vertex "tftest_dummy.d (expand)": visit complete, with errors
2023-01-09T10:52:39.591-0800 [TRACE] dag/walk: upstream of "provider[\"local/prashantv/tftest\"] (close)" errored, so skipping
2023-01-09T10:52:39.591-0800 [TRACE] dag/walk: upstream of "root" errored, so skipping
╷
│ Error: Plugin did not respond
│ 
│  with tftest_dummy.d,
│  on main.tf line 10, in resource "tftest_dummy" "d":
│  10: resource "tftest_dummy" "d" {
│ 
│ The plugin encountered an error, and failed to respond to the
│ plugin.(*GRPCProvider).PlanResourceChange call. The plugin logs may contain
│ more details.
╵
2023-01-09T10:52:39.592-0800 [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2023-01-09T10:52:39.592-0800 [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
Stack trace from the terraform-provider-tftest plugin:
panic: customize_diff called with CUSTOMIZE_DIFF_PANIC=1
goroutine 24 [running]:
github.com/prashantv/tf-test/internal/provider.dummyResource.func1({0x10096ebe0?, 0x14000184150?}, 0x10070203c?, {0xd?, 0x100704dfd?})
/Users/prashant/dev/3p/tf-bugs/terraform-customizediff-on-destroy-repro/internal/provider/dummy_resource.go:26 +0x58
github.com/hashicorp/terraform-plugin-sdk/v2/helper/schema.schemaMap.Diff(0x140003d30e0, {0x10096ebe0, 0x14000184150}, 0x14000434750, 0x14000184d50, 0x100964c80, {0x0, 0x0}, 0x0)
/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/schema.go:699 +0x3c4
github.com/hashicorp/terraform-plugin-sdk/v2/helper/schema.(*Resource).SimpleDiff(0x10096f2e0?, {0x10096ebe0?, 0x14000184150?}, 0x14000434750, 0x10089fe20?, {0x0?, 0x0?})
/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/resource.go:890 +0x50
github.com/hashicorp/terraform-plugin-sdk/v2/helper/schema.(*GRPCProviderServer).PlanResourceChange(0x14000121848, {0x10096ebe0?, 0x14000184030?}, 0x1400041a3c0)
/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-sdk/v2@v2.24.1/helper/schema/grpc_provider.go:741 +0x85c
github.com/hashicorp/terraform-plugin-go/tfprotov5/tf5server.(*server).PlanResourceChange(0x14000128c80, {0x10096ebe0?, 0x140001ca390?}, 0x140000d80e0)
/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:783 +0x3c0
github.com/hashicorp/terraform-plugin-go/tfprotov5/internal/tfplugin5._Provider_PlanResourceChange_Handler({0x10093e5e0?, 0x14000128c80}, {0x10096ebe0, 0x140001ca390}, 0x140000d8070, 0x0)
/Users/prashant/go/pkg/mod/github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tfplugin5/tfplugin5_grpc.pb.go:367 +0x174
google.golang.org/grpc.(*Server).processUnaryRPC(0x140003e2000, {0x100971700, 0x1400029a1a0}, 0x140003f8000, 0x140003d3200, 0x100db24c8, 0x0)
/Users/prashant/go/pkg/mod/google.golang.org/grpc@v1.51.0/server.go:1340 +0xb90
google.golang.org/grpc.(*Server).handleStream(0x140003e2000, {0x100971700, 0x1400029a1a0}, 0x140003f8000, 0x0)
/Users/prashant/go/pkg/mod/google.golang.org/grpc@v1.51.0/server.go:1713 +0x840
google.golang.org/grpc.(*Server).serveStreams.func1.2()
/Users/prashant/go/pkg/mod/google.golang.org/grpc@v1.51.0/server.go:965 +0x88
created by google.golang.org/grpc.(*Server).serveStreams.func1
/Users/prashant/go/pkg/mod/google.golang.org/grpc@v1.51.0/server.go:963 +0x298
Error: The terraform-provider-tftest plugin crashed!
This is always indicative of a bug within the plugin. It would be immensely
helpful if you could report the crash with the plugin's maintainers so that it
can be fixed. The output above should help diagnose the issue.
2023-01-09T10:52:39.592-0800 [DEBUG] provider: plugin exited
make: *** [destroy] Error 1
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment