terraform-plugin-framework#412 Initial reproduction on Terraform 1.2.5

terraform-plugin-framework-412-reproduction.txt

2022-07-26T17:06:18.242-0400 [TRACE] sdk.helper_resource: Validating TestCase: test_name=TestAccExampleResource
2022-07-26T17:06:18.242-0400 [TRACE] sdk.helper_resource: Validating TestStep: test_name=TestAccExampleResource test_step_number=1
2022-07-26T17:06:18.242-0400 [DEBUG] sdk.helper_resource: Starting TestCase: test_name=TestAccExampleResource
2022-07-26T17:06:18.242-0400 [DEBUG] sdk.helper_resource: Calling TestCase PreCheck: test_name=TestAccExampleResource
2022-07-26T17:06:18.242-0400 [DEBUG] sdk.helper_resource: Called TestCase PreCheck: test_name=TestAccExampleResource
2022-07-26T17:06:18.242-0400 [TRACE] sdk.helper_resource: Adding potential Terraform CLI source of local filesystem PATH lookup: test_name=TestAccExampleResource
2022-07-26T17:06:18.242-0400 [TRACE] sdk.helper_resource: Adding potential Terraform CLI source of checkpoint.hashicorp.com latest version for installation in: /var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest-terraform2998486999: test_name=TestAccExampleResource
2022-07-26T17:06:18.242-0400 [DEBUG] sdk.helper_resource: Found Terraform CLI: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform
2022-07-26T17:06:18.242-0400 [TRACE] sdk.helper_resource: Symlinking source directories to work directory: test_name=TestAccExampleResource test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363/work587928205
2022-07-26T17:06:18.271-0400 [TRACE] sdk.helper_resource: Setting terraform-exec log level via TF_ACC_LOG environment variable, if Terraform CLI is version 0.15 or later: test_name=TestAccExampleResource test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363/work587928205 test_terraform_log_level=TRACE
2022-07-26T17:06:18.271-0400 [TRACE] sdk.helper_resource: Setting terraform-exec log path via TF_ACC_LOG_PATH environment variable: test_name=TestAccExampleResource test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363/work587928205 test_terraform_log_path=/Users/bflad/test/terraform-provider-scaffolding-framework/test.txt
2022-07-26T17:06:18.271-0400 [TRACE] sdk.helper_resource: Clearing Terraform plan: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.271-0400 [TRACE] sdk.helper_resource: No Terraform plan to clear: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.271-0400 [DEBUG] sdk.helper_resource: Creating tfprotov6 provider instance: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.271-0400 [DEBUG] sdk.helper_resource: Created tfprotov6 provider instance: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.271-0400 [DEBUG] sdk.helper_resource: Starting tfprotov6 provider instance server: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.272-0400 [DEBUG] sdk.helper_resource: Started tfprotov6 provider instance server: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.272-0400 [TRACE] sdk.helper_resource: Setting Terraform CLI reattach configuration: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_reattach_config="map[registry.terraform.io/-/scaffolding:{grpc 6 18508 true {unix /var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugin1434064828}} registry.terraform.io/hashicorp/scaffolding:{grpc 6 18508 true {unix /var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugin1434064828}}]"
2022-07-26T17:06:18.273-0400 [TRACE] sdk.helper_resource: Calling wrapped Terraform CLI command: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.273-0400 [TRACE] sdk.helper_resource: Calling Terraform CLI init command: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.296-0400 [INFO]  Terraform version: 1.2.5
2022-07-26T17:06:18.296-0400 [DEBUG] using github.com/hashicorp/go-tfe v1.0.0
2022-07-26T17:06:18.296-0400 [DEBUG] using github.com/hashicorp/hcl/v2 v2.12.0
2022-07-26T17:06:18.296-0400 [DEBUG] using github.com/hashicorp/terraform-config-inspect v0.0.0-20210209133302-4fd17a0faac2
2022-07-26T17:06:18.296-0400 [DEBUG] using github.com/hashicorp/terraform-svchost v0.0.0-20200729002733-f050f53b9734
2022-07-26T17:06:18.296-0400 [DEBUG] using github.com/zclconf/go-cty v1.10.0
2022-07-26T17:06:18.296-0400 [INFO]  Go runtime version: go1.18.1
2022-07-26T17:06:18.296-0400 [INFO]  CLI args: []string{"/opt/homebrew/bin/terraform", "init", "-no-color", "-force-copy", "-input=false", "-backend=true", "-get=true", "-upgrade=true"}
2022-07-26T17:06:18.296-0400 [TRACE] Stdout is not a terminal
2022-07-26T17:06:18.296-0400 [TRACE] Stderr is not a terminal
2022-07-26T17:06:18.296-0400 [TRACE] Stdin is not a terminal
2022-07-26T17:06:18.296-0400 [DEBUG] Attempting to open CLI config file: /Users/bflad/.terraformrc
2022-07-26T17:06:18.296-0400 [INFO]  Loading CLI configuration from /Users/bflad/.terraformrc
2022-07-26T17:06:18.296-0400 [DEBUG] Using modified User-Agent: HashiCorp Terraform/1.2.5 (+https://www.terraform.io) HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.296-0400 [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2022-07-26T17:06:18.296-0400 [DEBUG] ignoring non-existing provider search directory /Users/bflad/.terraform.d/plugins
2022-07-26T17:06:18.296-0400 [DEBUG] ignoring non-existing provider search directory /Users/bflad/Library/Application Support/io.terraform/plugins
2022-07-26T17:06:18.296-0400 [DEBUG] ignoring non-existing provider search directory /Library/Application Support/io.terraform/plugins
2022-07-26T17:06:18.297-0400 [INFO]  CLI command args: []string{"init", "-no-color", "-force-copy", "-input=false", "-backend=true", "-get=true", "-upgrade=true"}
2022-07-26T17:06:18.297-0400 [DEBUG] Using modified User-Agent: Terraform/1.2.5 HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.297-0400 [DEBUG] Using modified User-Agent: HashiCorp Terraform/1.2.5 (+https://www.terraform.io) HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.297-0400 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2022-07-26T17:06:18.297-0400 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2022-07-26T17:06:18.297-0400 [DEBUG] New state was assigned lineage "f0a71f38-f5ca-b7db-aea5-4f851402055a"
2022-07-26T17:06:18.297-0400 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2022-07-26T17:06:18.297-0400 [TRACE] Meta.Backend: instantiated backend of type <nil>
2022-07-26T17:06:18.297-0400 [DEBUG] Provider registry.terraform.io/-/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.297-0400 [DEBUG] Provider registry.terraform.io/hashicorp/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.297-0400 [DEBUG] checking for provisioner in "."
2022-07-26T17:06:18.297-0400 [DEBUG] checking for provisioner in "/opt/homebrew/bin"
2022-07-26T17:06:18.297-0400 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2022-07-26T17:06:18.297-0400 [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
2022-07-26T17:06:18.297-0400 [TRACE] statemgr.Filesystem: reading initial snapshot from terraform.tfstate
2022-07-26T17:06:18.297-0400 [TRACE] statemgr.Filesystem: snapshot file has nil snapshot, but that's okay
2022-07-26T17:06:18.297-0400 [TRACE] statemgr.Filesystem: read nil snapshot
2022-07-26T17:06:18.297-0400 [DEBUG] Provider registry.terraform.io/-/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.297-0400 [DEBUG] Provider registry.terraform.io/hashicorp/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.298-0400 [TRACE] sdk.helper_resource: Called Terraform CLI init command: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [TRACE] sdk.helper_resource: Called wrapped Terraform CLI command: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [DEBUG] sdk.helper_resource: Stopping providers: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [TRACE] sdk.helper_resource: Waiting for providers to stop: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [TRACE] sdk.helper_resource: Providers have successfully stopped: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [DEBUG] sdk.helper_resource: Starting TestSteps: test_name=TestAccExampleResource test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [DEBUG] sdk.helper_resource: Starting TestStep: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [TRACE] sdk.helper_resource: TestStep is Config mode: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [TRACE] sdk.helper_resource: Clearing Terraform plan: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [TRACE] sdk.helper_resource: No Terraform plan to clear: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.298-0400 [DEBUG] sdk.helper_resource: Creating tfprotov6 provider instance: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.298-0400 [DEBUG] sdk.helper_resource: Created tfprotov6 provider instance: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.298-0400 [DEBUG] sdk.helper_resource: Starting tfprotov6 provider instance server: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.299-0400 [DEBUG] sdk.helper_resource: Started tfprotov6 provider instance server: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.299-0400 [TRACE] sdk.helper_resource: Setting Terraform CLI reattach configuration: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_reattach_config="map[registry.terraform.io/-/scaffolding:{grpc 6 18508 true {unix /var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugin3385497645}} registry.terraform.io/hashicorp/scaffolding:{grpc 6 18508 true {unix /var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugin3385497645}}]"
2022-07-26T17:06:18.299-0400 [TRACE] sdk.helper_resource: Calling wrapped Terraform CLI command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.299-0400 [TRACE] sdk.helper_resource: Calling Terraform CLI refresh command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.318-0400 [INFO]  Terraform version: 1.2.5
2022-07-26T17:06:18.318-0400 [DEBUG] using github.com/hashicorp/go-tfe v1.0.0
2022-07-26T17:06:18.318-0400 [DEBUG] using github.com/hashicorp/hcl/v2 v2.12.0
2022-07-26T17:06:18.318-0400 [DEBUG] using github.com/hashicorp/terraform-config-inspect v0.0.0-20210209133302-4fd17a0faac2
2022-07-26T17:06:18.318-0400 [DEBUG] using github.com/hashicorp/terraform-svchost v0.0.0-20200729002733-f050f53b9734
2022-07-26T17:06:18.318-0400 [DEBUG] using github.com/zclconf/go-cty v1.10.0
2022-07-26T17:06:18.318-0400 [INFO]  Go runtime version: go1.18.1
2022-07-26T17:06:18.318-0400 [INFO]  CLI args: []string{"/opt/homebrew/bin/terraform", "refresh", "-no-color", "-input=false", "-lock-timeout=0s", "-lock=true"}
2022-07-26T17:06:18.318-0400 [TRACE] Stdout is not a terminal
2022-07-26T17:06:18.318-0400 [TRACE] Stderr is not a terminal
2022-07-26T17:06:18.318-0400 [TRACE] Stdin is not a terminal
2022-07-26T17:06:18.318-0400 [DEBUG] Attempting to open CLI config file: /Users/bflad/.terraformrc
2022-07-26T17:06:18.318-0400 [INFO]  Loading CLI configuration from /Users/bflad/.terraformrc
2022-07-26T17:06:18.318-0400 [DEBUG] Using modified User-Agent: HashiCorp Terraform/1.2.5 (+https://www.terraform.io) HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.318-0400 [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2022-07-26T17:06:18.318-0400 [DEBUG] ignoring non-existing provider search directory /Users/bflad/.terraform.d/plugins
2022-07-26T17:06:18.318-0400 [DEBUG] ignoring non-existing provider search directory /Users/bflad/Library/Application Support/io.terraform/plugins
2022-07-26T17:06:18.318-0400 [DEBUG] ignoring non-existing provider search directory /Library/Application Support/io.terraform/plugins
2022-07-26T17:06:18.318-0400 [INFO]  CLI command args: []string{"refresh", "-no-color", "-input=false", "-lock-timeout=0s", "-lock=true"}
2022-07-26T17:06:18.318-0400 [DEBUG] Using modified User-Agent: Terraform/1.2.5 HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.318-0400 [DEBUG] Using modified User-Agent: HashiCorp Terraform/1.2.5 (+https://www.terraform.io) HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.319-0400 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2022-07-26T17:06:18.319-0400 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2022-07-26T17:06:18.319-0400 [DEBUG] New state was assigned lineage "120a7370-de3a-8f40-46f4-f474baa43de5"
2022-07-26T17:06:18.319-0400 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2022-07-26T17:06:18.319-0400 [TRACE] Meta.Backend: instantiated backend of type <nil>
2022-07-26T17:06:18.319-0400 [DEBUG] Provider registry.terraform.io/-/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.319-0400 [DEBUG] Provider registry.terraform.io/hashicorp/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.319-0400 [DEBUG] checking for provisioner in "."
2022-07-26T17:06:18.319-0400 [DEBUG] checking for provisioner in "/opt/homebrew/bin"
2022-07-26T17:06:18.319-0400 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2022-07-26T17:06:18.319-0400 [DEBUG] Provider registry.terraform.io/-/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.319-0400 [DEBUG] Provider registry.terraform.io/hashicorp/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.319-0400 [TRACE] backend/local: requesting state manager for workspace "default"
2022-07-26T17:06:18.319-0400 [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
2022-07-26T17:06:18.319-0400 [TRACE] backend/local: requesting state lock for workspace "default"
2022-07-26T17:06:18.319-0400 [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2022-07-26T17:06:18.319-0400 [TRACE] statemgr.Filesystem: no previously-stored snapshot exists
2022-07-26T17:06:18.319-0400 [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2022-07-26T17:06:18.319-0400 [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2022-07-26T17:06:18.319-0400 [TRACE] backend/local: reading remote state for workspace "default"
2022-07-26T17:06:18.319-0400 [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2022-07-26T17:06:18.319-0400 [TRACE] statemgr.Filesystem: snapshot file has nil snapshot, but that's okay
2022-07-26T17:06:18.319-0400 [TRACE] statemgr.Filesystem: read nil snapshot
2022-07-26T17:06:18.319-0400 [TRACE] backend/local: populating backend.LocalRun for current working directory
2022-07-26T17:06:18.320-0400 [DEBUG] Config.VerifyDependencySelections: skipping registry.terraform.io/hashicorp/scaffolding because it's overridden by a special configuration setting
2022-07-26T17:06:18.320-0400 [DEBUG] backend/local: Skipping interactive prompts for variables because input is disabled
2022-07-26T17:06:18.320-0400 [TRACE] terraform.NewContext: starting
2022-07-26T17:06:18.320-0400 [TRACE] terraform.NewContext: complete
2022-07-26T17:06:18.320-0400 [TRACE] backend/local: running validation operation
2022-07-26T17:06:18.320-0400 [DEBUG] Building and walking validate graph
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.ConfigTransformer
2022-07-26T17:06:18.320-0400 [TRACE] ConfigTransformer: Starting for path:
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  scaffolding_example.test - *terraform.NodeValidatableResource
  scaffolding_example.test2 - *terraform.NodeValidatableResource
  ------
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.LocalTransformer
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.OutputTransformer
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.StateTransformer
2022-07-26T17:06:18.320-0400 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2022-07-26T17:06:18.320-0400 [TRACE] AttachResourceConfigTransformer: attaching to "scaffolding_example.test2" (*terraform.NodeValidatableResource) config from terraform_plugin_test.tf:7,1-39
2022-07-26T17:06:18.320-0400 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to scaffolding_example.test2
2022-07-26T17:06:18.320-0400 [TRACE] AttachResourceConfigTransformer: attaching to "scaffolding_example.test" (*terraform.NodeValidatableResource) config from terraform_plugin_test.tf:2,1-38
2022-07-26T17:06:18.320-0400 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to scaffolding_example.test
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2022-07-26T17:06:18.320-0400 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2022-07-26T17:06:18.320-0400 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  scaffolding_example.test - *terraform.NodeValidatableResource
  scaffolding_example.test2 - *terraform.NodeValidatableResource
  ------
2022-07-26T17:06:18.320-0400 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2022-07-26T17:06:18.320-0400 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/scaffolding"], implied first by scaffolding_example.test
2022-07-26T17:06:18.320-0400 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
  provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test - *terraform.NodeValidatableResource
  scaffolding_example.test2 - *terraform.NodeValidatableResource
  ------
2022-07-26T17:06:18.320-0400 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2022-07-26T17:06:18.320-0400 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/scaffolding"] serving scaffolding_example.test
2022-07-26T17:06:18.320-0400 [DEBUG] ProviderTransformer: "scaffolding_example.test" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/scaffolding"]
2022-07-26T17:06:18.320-0400 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/scaffolding"] serving scaffolding_example.test2
2022-07-26T17:06:18.320-0400 [DEBUG] ProviderTransformer: "scaffolding_example.test2" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/scaffolding"]
2022-07-26T17:06:18.320-0400 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test2 - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  ------
2022-07-26T17:06:18.320-0400 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2022-07-26T17:06:18.320-0400 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test2 - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  ------
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2022-07-26T17:06:18.320-0400 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2022-07-26T17:06:18.320-0400 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2022-07-26T17:06:18.320-0400 [TRACE] terraform.contextPlugins: Initializing provider "registry.terraform.io/hashicorp/scaffolding" to read its schema
2022-07-26T17:06:18.320-0400 [DEBUG] created provider logger: level=trace
2022-07-26T17:06:18.320-0400 [TRACE] GRPCProvider.v6: GetProviderSchema
2022-07-26T17:06:18.320-0400 [TRACE] unmanaged.provider.stdio: waiting for stdio data
2022-07-26T17:06:18.321-0400 [TRACE] sdk.proto: Received request: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.321-0400 [TRACE] sdk.proto: Sending request downstream: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.321-0400 [TRACE] sdk.framework: Checking ProviderSchema lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [DEBUG] sdk.framework: Calling provider defined Provider GetSchema: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [DEBUG] sdk.framework: Called provider defined Provider GetSchema: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [TRACE] sdk.framework: Checking ResourceSchemas lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [TRACE] sdk.framework: Checking ResourceTypes lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [DEBUG] sdk.framework: Calling provider defined Provider GetResources: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [DEBUG] sdk.framework: Called provider defined Provider GetResources: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [TRACE] sdk.framework: Found resource type: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema tf_resource_type=scaffolding_example
2022-07-26T17:06:18.322-0400 [DEBUG] sdk.framework: Calling provider defined ResourceType GetSchema: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema tf_resource_type=scaffolding_example
2022-07-26T17:06:18.322-0400 [DEBUG] sdk.framework: Called provider defined ResourceType GetSchema: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema tf_resource_type=scaffolding_example
2022-07-26T17:06:18.322-0400 [TRACE] sdk.framework: Checking DataSourceSchemas lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [TRACE] sdk.framework: Checking DataSourceTypes lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [DEBUG] sdk.framework: Calling provider defined Provider GetDataSources: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [DEBUG] sdk.framework: Called provider defined Provider GetDataSources: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [TRACE] sdk.proto: Received downstream response: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema tf_req_duration_ms=0 diagnostic_error_count=0 diagnostic_warning_count=0
2022-07-26T17:06:18.322-0400 [TRACE] sdk.proto: Served request: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=2d6bda72-63e9-dfbc-b8b2-c31c1b33e96c tf_rpc=GetProviderSchema
2022-07-26T17:06:18.322-0400 [DEBUG] No provider meta schema returned
2022-07-26T17:06:18.322-0400 [TRACE] GRPCProvider.v6: Close
2022-07-26T17:06:18.322-0400 [TRACE] AttachSchemaTransformer: attaching resource schema to scaffolding_example.test
2022-07-26T17:06:18.322-0400 [TRACE] AttachSchemaTransformer: attaching resource schema to scaffolding_example.test2
2022-07-26T17:06:18.322-0400 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/scaffolding"]
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2022-07-26T17:06:18.322-0400 [DEBUG] ReferenceTransformer: "scaffolding_example.test" references: []
2022-07-26T17:06:18.322-0400 [DEBUG] ReferenceTransformer: "scaffolding_example.test2" references: [scaffolding_example.test]
2022-07-26T17:06:18.322-0400 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/scaffolding\"]" references: []
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
  provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test2 - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
    scaffolding_example.test - *terraform.NodeValidatableResource
  ------
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.TargetsTransformer
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/scaffolding"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
    scaffolding_example.test - *terraform.NodeValidatableResource
    scaffolding_example.test2 - *terraform.NodeValidatableResource
  scaffolding_example.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test2 - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
    scaffolding_example.test - *terraform.NodeValidatableResource
  ------
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/scaffolding"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
    scaffolding_example.test - *terraform.NodeValidatableResource
    scaffolding_example.test2 - *terraform.NodeValidatableResource
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/scaffolding"] (close) - *terraform.graphNodeCloseProvider
  scaffolding_example.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test2 - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
    scaffolding_example.test - *terraform.NodeValidatableResource
  ------
2022-07-26T17:06:18.322-0400 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2022-07-26T17:06:18.322-0400 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/scaffolding"] (close) - *terraform.graphNodeCloseProvider
    scaffolding_example.test2 - *terraform.NodeValidatableResource
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/scaffolding"] (close) - *terraform.graphNodeCloseProvider
  scaffolding_example.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/scaffolding"] - *terraform.NodeApplyableProvider
  scaffolding_example.test2 - *terraform.NodeValidatableResource
    scaffolding_example.test - *terraform.NodeValidatableResource
  ------
2022-07-26T17:06:18.322-0400 [DEBUG] Starting graph walk: walkValidate
2022-07-26T17:06:18.322-0400 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/scaffolding\"]": starting visit (*terraform.NodeApplyableProvider)
2022-07-26T17:06:18.322-0400 [DEBUG] created provider logger: level=trace
2022-07-26T17:06:18.322-0400 [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/hashicorp/scaffolding\"]" provider for provider["registry.terraform.io/hashicorp/scaffolding"]
2022-07-26T17:06:18.322-0400 [TRACE] NodeApplyableProvider: validating configuration for provider["registry.terraform.io/hashicorp/scaffolding"]
2022-07-26T17:06:18.322-0400 [TRACE] buildProviderConfig for provider["registry.terraform.io/hashicorp/scaffolding"]: no configuration at all
2022-07-26T17:06:18.322-0400 [TRACE] unmanaged.provider.stdio: waiting for stdio data
2022-07-26T17:06:18.322-0400 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/scaffolding\"]": visit complete
2022-07-26T17:06:18.322-0400 [TRACE] vertex "scaffolding_example.test": starting visit (*terraform.NodeValidatableResource)
2022-07-26T17:06:18.323-0400 [DEBUG] skipping FixUpBlockAttrs
2022-07-26T17:06:18.323-0400 [TRACE] GRPCProvider.v6: ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] GRPCProvider.v6: GetProviderSchema
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: Received request: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=3d70e745-0300-7f36-cdf5-c7ffa04fdd98 tf_rpc=GetProviderSchema
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: Sending request downstream: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=3d70e745-0300-7f36-cdf5-c7ffa04fdd98 tf_rpc=GetProviderSchema
2022-07-26T17:06:18.323-0400 [TRACE] sdk.framework: Checking ProviderSchema lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=3d70e745-0300-7f36-cdf5-c7ffa04fdd98 tf_rpc=GetProviderSchema
2022-07-26T17:06:18.323-0400 [TRACE] sdk.framework: Checking ResourceSchemas lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=3d70e745-0300-7f36-cdf5-c7ffa04fdd98 tf_rpc=GetProviderSchema
2022-07-26T17:06:18.323-0400 [TRACE] sdk.framework: Checking DataSourceSchemas lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=3d70e745-0300-7f36-cdf5-c7ffa04fdd98 tf_rpc=GetProviderSchema
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: Received downstream response: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=3d70e745-0300-7f36-cdf5-c7ffa04fdd98 tf_rpc=GetProviderSchema diagnostic_warning_count=0 tf_req_duration_ms=0 diagnostic_error_count=0
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: Served request: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=3d70e745-0300-7f36-cdf5-c7ffa04fdd98 tf_rpc=GetProviderSchema
2022-07-26T17:06:18.323-0400 [DEBUG] No provider meta schema returned
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: Received request: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: 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_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: Sending request downstream: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] sdk.framework: Checking ResourceTypes lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] sdk.framework: Checking ResourceSchemas lock: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [DEBUG] sdk.framework: Calling provider defined ResourceType NewResource: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [DEBUG] sdk.framework: Called provider defined ResourceType NewResource: tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] sdk.framework: Type implements TypeWithValidate: tf_attribute_path="AttributeName(\"id\")" tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [DEBUG] sdk.framework: Calling provider defined Type Validate: tf_attribute_path="AttributeName(\"id\")" tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [DEBUG] sdk.framework: Called provider defined Type Validate: tf_attribute_path="AttributeName(\"id\")" tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] sdk.framework: Type implements TypeWithValidate: tf_attribute_path="AttributeName(\"metadata\").AttributeName(\"name\")" tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [DEBUG] sdk.framework: Calling provider defined Type Validate: tf_attribute_path="AttributeName(\"metadata\").AttributeName(\"name\")" tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [DEBUG] sdk.framework: Called provider defined Type Validate: tf_attribute_path="AttributeName(\"metadata\").AttributeName(\"name\")" tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: Received downstream response: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig diagnostic_warning_count=0 tf_req_duration_ms=0 diagnostic_error_count=0
2022-07-26T17:06:18.323-0400 [TRACE] sdk.proto: Served request: tf_proto_version=6.3 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding tf_req_id=bf098992-5d0b-1f50-743c-f4a467a10a92 tf_resource_type=scaffolding_example tf_rpc=ValidateResourceConfig
2022-07-26T17:06:18.323-0400 [TRACE] vertex "scaffolding_example.test": visit complete
2022-07-26T17:06:18.323-0400 [TRACE] vertex "scaffolding_example.test2": starting visit (*terraform.NodeValidatableResource)
2022-07-26T17:06:18.323-0400 [ERROR] vertex "scaffolding_example.test2" error: Unsupported attribute
2022-07-26T17:06:18.323-0400 [TRACE] vertex "scaffolding_example.test2": visit complete, with errors
2022-07-26T17:06:18.323-0400 [TRACE] dag/walk: upstream of "provider[\"registry.terraform.io/hashicorp/scaffolding\"] (close)" errored, so skipping
2022-07-26T17:06:18.323-0400 [TRACE] dag/walk: upstream of "root" errored, so skipping
2022-07-26T17:06:18.323-0400 [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2022-07-26T17:06:18.324-0400 [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
2022-07-26T17:06:18.325-0400 [TRACE] sdk.helper_resource: Called Terraform CLI refresh command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.325-0400 [WARN]  sdk.helper_resource: Error running Terraform CLI command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
  error=
  | exit status 1
  | 
  | Error: Unsupported attribute
  | 
  |   on terraform_plugin_test.tf line 9, in resource "scaffolding_example" "test2":
  |    9: 	  name = scaffolding_example.test.metadata.name
  | 
  | This value does not have any attributes.
  
2022-07-26T17:06:18.325-0400 [TRACE] sdk.helper_resource: Called wrapped Terraform CLI command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.325-0400 [DEBUG] sdk.helper_resource: Stopping providers: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.325-0400 [TRACE] sdk.helper_resource: Waiting for providers to stop: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.325-0400 [TRACE] sdk.helper_resource: Providers have successfully stopped: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.325-0400 [ERROR] sdk.helper_resource: Unexpected error: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
  error=
  | Error running pre-apply refresh: exit status 1
  | 
  | Error: Unsupported attribute
  | 
  |   on terraform_plugin_test.tf line 9, in resource "scaffolding_example" "test2":
  |    9: 	  name = scaffolding_example.test.metadata.name
  | 
  | This value does not have any attributes.
  
2022-07-26T17:06:18.325-0400 [DEBUG] sdk.helper_resource: Creating tfprotov6 provider instance: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.325-0400 [DEBUG] sdk.helper_resource: Created tfprotov6 provider instance: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.325-0400 [DEBUG] sdk.helper_resource: Starting tfprotov6 provider instance server: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.325-0400 [DEBUG] sdk.helper_resource: Started tfprotov6 provider instance server: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_provider_addr=registry.terraform.io/hashicorp/scaffolding
2022-07-26T17:06:18.325-0400 [TRACE] sdk.helper_resource: Setting Terraform CLI reattach configuration: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363 tf_reattach_config="map[registry.terraform.io/-/scaffolding:{grpc 6 18508 true {unix /var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugin755518787}} registry.terraform.io/hashicorp/scaffolding:{grpc 6 18508 true {unix /var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugin755518787}}]"
2022-07-26T17:06:18.325-0400 [TRACE] sdk.helper_resource: Calling wrapped Terraform CLI command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.325-0400 [TRACE] sdk.helper_resource: Calling Terraform CLI show command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.345-0400 [INFO]  Terraform version: 1.2.5
2022-07-26T17:06:18.345-0400 [DEBUG] using github.com/hashicorp/go-tfe v1.0.0
2022-07-26T17:06:18.345-0400 [DEBUG] using github.com/hashicorp/hcl/v2 v2.12.0
2022-07-26T17:06:18.345-0400 [DEBUG] using github.com/hashicorp/terraform-config-inspect v0.0.0-20210209133302-4fd17a0faac2
2022-07-26T17:06:18.345-0400 [DEBUG] using github.com/hashicorp/terraform-svchost v0.0.0-20200729002733-f050f53b9734
2022-07-26T17:06:18.345-0400 [DEBUG] using github.com/zclconf/go-cty v1.10.0
2022-07-26T17:06:18.345-0400 [INFO]  Go runtime version: go1.18.1
2022-07-26T17:06:18.345-0400 [INFO]  CLI args: []string{"/opt/homebrew/bin/terraform", "show", "-json", "-no-color"}
2022-07-26T17:06:18.345-0400 [TRACE] Stdout is not a terminal
2022-07-26T17:06:18.345-0400 [TRACE] Stderr is not a terminal
2022-07-26T17:06:18.345-0400 [TRACE] Stdin is not a terminal
2022-07-26T17:06:18.345-0400 [DEBUG] Attempting to open CLI config file: /Users/bflad/.terraformrc
2022-07-26T17:06:18.345-0400 [INFO]  Loading CLI configuration from /Users/bflad/.terraformrc
2022-07-26T17:06:18.346-0400 [DEBUG] Using modified User-Agent: HashiCorp Terraform/1.2.5 (+https://www.terraform.io) HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.346-0400 [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2022-07-26T17:06:18.346-0400 [DEBUG] ignoring non-existing provider search directory /Users/bflad/.terraform.d/plugins
2022-07-26T17:06:18.346-0400 [DEBUG] ignoring non-existing provider search directory /Users/bflad/Library/Application Support/io.terraform/plugins
2022-07-26T17:06:18.346-0400 [DEBUG] ignoring non-existing provider search directory /Library/Application Support/io.terraform/plugins
2022-07-26T17:06:18.346-0400 [INFO]  CLI command args: []string{"show", "-json", "-no-color"}
2022-07-26T17:06:18.346-0400 [DEBUG] Using modified User-Agent: Terraform/1.2.5 HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.346-0400 [DEBUG] Using modified User-Agent: HashiCorp Terraform/1.2.5 (+https://www.terraform.io) HashiCorp-terraform-exec/0.17.2
2022-07-26T17:06:18.346-0400 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2022-07-26T17:06:18.346-0400 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2022-07-26T17:06:18.346-0400 [DEBUG] New state was assigned lineage "976d09a5-cb87-9a3f-6444-21f9f257fe05"
2022-07-26T17:06:18.346-0400 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2022-07-26T17:06:18.346-0400 [TRACE] Meta.Backend: instantiated backend of type <nil>
2022-07-26T17:06:18.346-0400 [DEBUG] Provider registry.terraform.io/-/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.346-0400 [DEBUG] Provider registry.terraform.io/hashicorp/scaffolding is overridden as an "unmanaged provider"
2022-07-26T17:06:18.346-0400 [DEBUG] checking for provisioner in "."
2022-07-26T17:06:18.346-0400 [DEBUG] checking for provisioner in "/opt/homebrew/bin"
2022-07-26T17:06:18.346-0400 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2022-07-26T17:06:18.346-0400 [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
2022-07-26T17:06:18.346-0400 [TRACE] statemgr.Filesystem: reading initial snapshot from terraform.tfstate
2022-07-26T17:06:18.346-0400 [TRACE] statemgr.Filesystem: snapshot file has nil snapshot, but that's okay
2022-07-26T17:06:18.346-0400 [TRACE] statemgr.Filesystem: read nil snapshot
2022-07-26T17:06:18.347-0400 [TRACE] sdk.helper_resource: Called Terraform CLI show command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.348-0400 [TRACE] sdk.helper_resource: Called wrapped Terraform CLI command: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.348-0400 [DEBUG] sdk.helper_resource: Stopping providers: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.348-0400 [TRACE] sdk.helper_resource: Waiting for providers to stop: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363
2022-07-26T17:06:18.348-0400 [TRACE] sdk.helper_resource: Providers have successfully stopped: test_name=TestAccExampleResource test_step_number=1 test_terraform_path=/opt/homebrew/bin/terraform test_working_directory=/var/folders/f3/2mhr8hkx72z9dllv0ry81zm40000gq/T/plugintest1080119363