tf_debug_log_20200211

2021/02/11 15:29:46 [INFO] Terraform version: 0.14.6  
2021/02/11 15:29:46 [INFO] Go runtime version: go1.15.6
2021/02/11 15:29:46 [INFO] CLI args: []string{"/usr/local/bin/terraform", "apply"}
2021/02/11 15:29:46 [DEBUG] Attempting to open CLI config file: /Users/cyrusjavan/.terraformrc
2021/02/11 15:29:46 Loading CLI configuration from /Users/cyrusjavan/.terraformrc
2021/02/11 15:29:46 [DEBUG] checking for credentials in "/Users/cyrusjavan/.terraform.d/plugins"
2021/02/11 15:29:46 [DEBUG] Explicit provider installation configuration is set
2021/02/11 15:29:46 [TRACE] Selected provider installation method cliconfig.ProviderInstallationFilesystemMirror("/Users/cyrusjavan/.terraform.d/plugins") with includes [example.com/*/*] and excludes []
2021/02/11 15:29:46 [TRACE] Selected provider installation method cliconfig.ProviderInstallationDirect with includes [] and excludes [example.com/*/*]
2021/02/11 15:29:46 [INFO] CLI command args: []string{"apply"}
2021/02/11 15:29:46 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2021/02/11 15:29:46 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2021/02/11 15:29:46 [DEBUG] New state was assigned lineage "d45f9056-1aff-90e6-719c-973278d0245b"
2021/02/11 15:29:46 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2021/02/11 15:29:46 [TRACE] Meta.Backend: instantiated backend of type <nil>
2021/02/11 15:29:46 [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2021/02/11 15:29:46 [TRACE] getproviders.SearchLocalDirectory: .terraform/providers is a symlink to .terraform/providers
2021/02/11 15:29:46 [TRACE] getproviders.SearchLocalDirectory: found example.com/example/example v0.0.1 for darwin_amd64 at .terraform/providers/example.com/example/example/0.0.1/darwin_amd64
2021/02/11 15:29:46 [TRACE] providercache.fillMetaCache: including .terraform/providers/example.com/example/example/0.0.1/darwin_amd64 as a candidate package for example.com/example/example 0.0.1
2021/02/11 15:29:47 [DEBUG] Provider registry.terraform.io/aviatrixsystems/aviatrix is overridden to load from /Users/cyrusjavan/.terraform.d/plugins/aviatrix.com/aviatrix/aviatrix/99.0.0/darwin_amd64
2021/02/11 15:29:47 [DEBUG] Provider example.com/example/example is overridden to load from /Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64
2021/02/11 15:29:47 [DEBUG] checking for provisioner in "."
2021/02/11 15:29:47 [DEBUG] checking for provisioner in "/usr/local/bin"
2021/02/11 15:29:47 [DEBUG] checking for provisioner in "/Users/cyrusjavan/.terraform.d/plugins"
2021/02/11 15:29:47 [INFO] Failed to read plugin lock file .terraform/plugins/darwin_amd64/lock.json: open .terraform/plugins/darwin_amd64/lock.json: no such file or directory
2021/02/11 15:29:47 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend

Warning: Provider development overrides are in effect

The following provider development overrides are set in the CLI configuration:
 - aviatrixsystems/aviatrix in /Users/cyrusjavan/.terraform.d/plugins/aviatrix.com/aviatrix/aviatrix/99.0.0/darwin_amd64
 - example.com/example/example in /Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64

The behavior may therefore not match any released version of the provider and
applying changes may cause the state to become incompatible with published
releases.

2021/02/11 15:29:47 [INFO] backend/local: starting Apply operation
2021/02/11 15:29:47 [TRACE] backend/local: requesting state manager for workspace "default"
2021/02/11 15:29:47 [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
2021/02/11 15:29:47 [TRACE] backend/local: requesting state lock for workspace "default"
2021/02/11 15:29:47 [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2021/02/11 15:29:47 [TRACE] statemgr.Filesystem: existing snapshot has lineage "e255e682-19c9-b561-4d68-94deca8a5947" serial 36
2021/02/11 15:29:47 [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2021/02/11 15:29:47 [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2021/02/11 15:29:47 [TRACE] backend/local: reading remote state for workspace "default"
2021/02/11 15:29:47 [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2021/02/11 15:29:47 [TRACE] statemgr.Filesystem: read snapshot with lineage "e255e682-19c9-b561-4d68-94deca8a5947" serial 36
2021/02/11 15:29:47 [TRACE] backend/local: retrieving local state snapshot for workspace "default"
2021/02/11 15:29:47 [TRACE] backend/local: building context for current working directory
2021/02/11 15:29:47 [TRACE] terraform.NewContext: starting
2021/02/11 15:29:47 [TRACE] terraform.NewContext: loading provider schemas
2021/02/11 15:29:47 [TRACE] LoadSchemas: retrieving schema for provider type "example.com/example/example"
2021-02-11T15:29:47.108-0800 [INFO]  plugin: configuring client automatic mTLS
2021-02-11T15:29:47.140-0800 [DEBUG] plugin: starting plugin: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 args=[/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1]
2021-02-11T15:29:47.143-0800 [DEBUG] plugin: plugin started: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 pid=19626
2021-02-11T15:29:47.143-0800 [DEBUG] plugin: waiting for RPC address: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1
2021-02-11T15:29:47.156-0800 [INFO]  plugin.terraform-provider-example_v0.0.1: configuring server automatic mTLS: timestamp=2021-02-11T15:29:47.155-0800
2021-02-11T15:29:47.185-0800 [DEBUG] plugin: using plugin: version=5
2021-02-11T15:29:47.185-0800 [DEBUG] plugin.terraform-provider-example_v0.0.1: plugin address: address=/var/folders/vt/vmymrq7d7194bv32slwtmdvh0000gn/T/plugin656064897 network=unix timestamp=2021-02-11T15:29:47.185-0800
2021/02/11 15:29:47 [TRACE] GRPCProvider: GetSchema
2021-02-11T15:29:47.251-0800 [TRACE] plugin.stdio: waiting for stdio data
2021/02/11 15:29:47 [TRACE] GRPCProvider: Close
2021-02-11T15:29:47.255-0800 [WARN]  plugin.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-02-11T15:29:47.256-0800 [DEBUG] plugin: plugin process exited: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 pid=19626
2021-02-11T15:29:47.256-0800 [DEBUG] plugin: plugin exited
2021/02/11 15:29:47 [TRACE] terraform.NewContext: complete
2021/02/11 15:29:47 [TRACE] backend/local: finished building terraform.Context
2021/02/11 15:29:47 [TRACE] backend/local: requesting interactive input, if necessary
2021/02/11 15:29:47 [TRACE] Context.Input: Prompting for provider arguments
2021/02/11 15:29:47 [TRACE] Context.Input: Provider provider.example declared at pet.tf:1,1-19
2021/02/11 15:29:47 [TRACE] Context.Input: Input for provider.example: map[string]cty.Value{}
2021/02/11 15:29:47 [TRACE] backend/local: running validation operation
2021/02/11 15:29:47 [INFO] terraform: building graph: GraphTypeValidate
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ConfigTransformer
2021/02/11 15:29:47 [TRACE] ConfigTransformer: Starting for path: 
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeValidatableResource
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.LocalTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.OutputTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.StateTransformer
2021/02/11 15:29:47 [TRACE] StateTransformer: state is empty, so nothing to do
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021/02/11 15:29:47 [TRACE] AttachResourceConfigTransformer: attaching to "example_pet.pet_1" (*terraform.NodeValidatableResource) config from pet.tf:11,1-31
2021/02/11 15:29:47 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to example_pet.pet_1
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ProvisionerTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021/02/11 15:29:47 [TRACE] ProviderConfigTransformer: attaching to "provider[\"example.com/example/example\"]" provider configuration from pet.tf:1,1-19
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeValidatableResource
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021/02/11 15:29:47 [TRACE] ProviderTransformer: exact match for provider["example.com/example/example"] serving example_pet.pet_1
2021/02/11 15:29:47 [DEBUG] ProviderTransformer: "example_pet.pet_1" (*terraform.NodeValidatableResource) needs provider["example.com/example/example"]
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021/02/11 15:29:47 [TRACE] AttachSchemaTransformer: attaching resource schema to example_pet.pet_1
2021/02/11 15:29:47 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["example.com/example/example"]
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021/02/11 15:29:47 [DEBUG] ReferenceTransformer: "example_pet.pet_1" references: []
2021/02/11 15:29:47 [DEBUG] ReferenceTransformer: "provider[\"example.com/example/example\"]" references: []
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.attachDataResourceDependenciesTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.attachDataResourceDependenciesTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.TargetsTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeValidatableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 - *terraform.NodeValidatableResource
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 - *terraform.NodeValidatableResource
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
  ------
2021/02/11 15:29:47 [DEBUG] Starting graph walk: walkValidate
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "provider[\"example.com/example/example\"]"
2021/02/11 15:29:47 [TRACE] vertex "provider[\"example.com/example/example\"]": starting visit (*terraform.NodeApplyableProvider)
2021-02-11T15:29:47.262-0800 [INFO]  plugin: configuring client automatic mTLS
2021-02-11T15:29:47.286-0800 [DEBUG] plugin: starting plugin: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 args=[/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1]
2021-02-11T15:29:47.290-0800 [DEBUG] plugin: plugin started: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 pid=19629
2021-02-11T15:29:47.290-0800 [DEBUG] plugin: waiting for RPC address: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1
2021-02-11T15:29:47.302-0800 [INFO]  plugin.terraform-provider-example_v0.0.1: configuring server automatic mTLS: timestamp=2021-02-11T15:29:47.302-0800
2021-02-11T15:29:47.328-0800 [DEBUG] plugin.terraform-provider-example_v0.0.1: plugin address: address=/var/folders/vt/vmymrq7d7194bv32slwtmdvh0000gn/T/plugin984111536 network=unix timestamp=2021-02-11T15:29:47.328-0800
2021-02-11T15:29:47.328-0800 [DEBUG] plugin: using plugin: version=5
2021/02/11 15:29:47 [TRACE] BuiltinEvalContext: Initialized "provider[\"example.com/example/example\"]" provider for provider["example.com/example/example"]
2021-02-11T15:29:47.380-0800 [TRACE] plugin.stdio: waiting for stdio data
2021/02/11 15:29:47 [TRACE] buildProviderConfig for provider["example.com/example/example"]: using explicit config only
2021/02/11 15:29:47 [TRACE] GRPCProvider: GetSchema
2021/02/11 15:29:47 [TRACE] GRPCProvider: PrepareProviderConfig
2021/02/11 15:29:47 [TRACE] vertex "provider[\"example.com/example/example\"]": visit complete
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "example_pet.pet_1"
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": starting visit (*terraform.NodeValidatableResource)
2021/02/11 15:29:47 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": visit complete
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "meta.count-boundary (EachMode fixup)"
2021/02/11 15:29:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "provider[\"example.com/example/example\"] (close)"
2021/02/11 15:29:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2021/02/11 15:29:47 [TRACE] vertex "provider[\"example.com/example/example\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2021/02/11 15:29:47 [TRACE] GRPCProvider: Close
2021-02-11T15:29:47.385-0800 [WARN]  plugin.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-02-11T15:29:47.387-0800 [DEBUG] plugin: plugin process exited: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 pid=19629
2021-02-11T15:29:47.387-0800 [DEBUG] plugin: plugin exited
2021/02/11 15:29:47 [TRACE] vertex "provider[\"example.com/example/example\"] (close)": visit complete
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "root"
2021/02/11 15:29:47 [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2021/02/11 15:29:47 [TRACE] vertex "root": visit complete
2021/02/11 15:29:47 [INFO] backend/local: apply calling Plan
2021/02/11 15:29:47 [INFO] terraform: building graph: GraphTypePlan
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ConfigTransformer
2021/02/11 15:29:47 [TRACE] ConfigTransformer: Starting for path: 
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.LocalTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.OutputTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.StateTransformer
2021/02/11 15:29:47 [TRACE] StateTransformer: state is empty, so nothing to do
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021/02/11 15:29:47 [TRACE] AttachResourceConfigTransformer: attaching to "example_pet.pet_1 (expand)" (*terraform.nodeExpandPlannableResource) config from pet.tf:11,1-31
2021/02/11 15:29:47 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to example_pet.pet_1 (expand)
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ProvisionerTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021/02/11 15:29:47 [TRACE] ProviderConfigTransformer: attaching to "provider[\"example.com/example/example\"]" provider configuration from pet.tf:1,1-19
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021/02/11 15:29:47 [TRACE] ProviderTransformer: exact match for provider["example.com/example/example"] serving example_pet.pet_1 (expand)
2021/02/11 15:29:47 [DEBUG] ProviderTransformer: "example_pet.pet_1 (expand)" (*terraform.nodeExpandPlannableResource) needs provider["example.com/example/example"]
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2021/02/11 15:29:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021/02/11 15:29:47 [TRACE] AttachSchemaTransformer: attaching resource schema to example_pet.pet_1 (expand)
2021/02/11 15:29:47 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["example.com/example/example"]
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021/02/11 15:29:47 [DEBUG] ReferenceTransformer: "example_pet.pet_1 (expand)" references: []
2021/02/11 15:29:47 [DEBUG] ReferenceTransformer: "provider[\"example.com/example/example\"]" references: []
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021/02/11 15:29:47 [TRACE] AttachDependenciesTransformer: example_pet.pet_1 depends on []
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.attachDataResourceDependenciesTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.attachDataResourceDependenciesTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.TargetsTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021/02/11 15:29:47 [TRACE] ForcedCBDTransformer: "example_pet.pet_1 (expand)" (*terraform.nodeExpandPlannableResource) has no CBD descendent, so skipping
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 (expand) - *terraform.nodeExpandPlannableResource
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
  ------
2021/02/11 15:29:47 [DEBUG] Starting graph walk: walkPlan
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "provider[\"example.com/example/example\"]"
2021/02/11 15:29:47 [TRACE] vertex "provider[\"example.com/example/example\"]": starting visit (*terraform.NodeApplyableProvider)
2021-02-11T15:29:47.388-0800 [INFO]  plugin: configuring client automatic mTLS
2021-02-11T15:29:47.413-0800 [DEBUG] plugin: starting plugin: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 args=[/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1]
2021-02-11T15:29:47.416-0800 [DEBUG] plugin: plugin started: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 pid=19630
2021-02-11T15:29:47.416-0800 [DEBUG] plugin: waiting for RPC address: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1
2021-02-11T15:29:47.429-0800 [INFO]  plugin.terraform-provider-example_v0.0.1: configuring server automatic mTLS: timestamp=2021-02-11T15:29:47.428-0800
2021-02-11T15:29:47.455-0800 [DEBUG] plugin: using plugin: version=5
2021-02-11T15:29:47.455-0800 [DEBUG] plugin.terraform-provider-example_v0.0.1: plugin address: network=unix address=/var/folders/vt/vmymrq7d7194bv32slwtmdvh0000gn/T/plugin858743589 timestamp=2021-02-11T15:29:47.455-0800
2021/02/11 15:29:47 [TRACE] BuiltinEvalContext: Initialized "provider[\"example.com/example/example\"]" provider for provider["example.com/example/example"]
2021-02-11T15:29:47.507-0800 [TRACE] plugin.stdio: waiting for stdio data
2021/02/11 15:29:47 [TRACE] buildProviderConfig for provider["example.com/example/example"]: using explicit config only
2021/02/11 15:29:47 [TRACE] GRPCProvider: GetSchema
2021/02/11 15:29:47 [TRACE] GRPCProvider: Configure
2021/02/11 15:29:47 [TRACE] vertex "provider[\"example.com/example/example\"]": visit complete
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "example_pet.pet_1 (expand)"
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1 (expand)": starting visit (*terraform.nodeExpandPlannableResource)
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1 (expand)": expanding dynamic subgraph
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1 (expand)": entering dynamic subgraph
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "example_pet.pet_1"
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": starting visit (*terraform.NodePlannableResource)
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": expanding dynamic subgraph
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2021/02/11 15:29:47 [TRACE] ResourceCountTransformer: adding example_pet.pet_1 as *terraform.NodePlannableResourceInstance
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
  example_pet.pet_1 - *terraform.NodePlannableResourceInstance
  ------
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceCountTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceCountTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021/02/11 15:29:47 [DEBUG] Resource instance state not found for node "example_pet.pet_1", instance example_pet.pet_1
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.TargetsTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021/02/11 15:29:47 [DEBUG] ReferenceTransformer: "example_pet.pet_1" references: []
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021/02/11 15:29:47 [TRACE] Executing graph transform *terraform.RootTransformer
2021/02/11 15:29:47 [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": entering dynamic subgraph
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "example_pet.pet_1"
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": starting visit (*terraform.NodePlannableResourceInstance)
2021/02/11 15:29:47 [TRACE] ReadResourceInstanceState: reading state for example_pet.pet_1
2021/02/11 15:29:47 [TRACE] ReadResourceInstanceState: no state present for example_pet.pet_1
2021/02/11 15:29:47 [DEBUG] refresh: example_pet.pet_1: no state, so not refreshing
2021/02/11 15:29:47 [TRACE] EvalWriteState: using RefreshState for example_pet.pet_1
2021/02/11 15:29:47 [TRACE] EvalWriteState: removing state object for example_pet.pet_1
2021/02/11 15:29:47 [TRACE] Re-validating config for "example_pet.pet_1"
2021/02/11 15:29:47 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2021/02/11 15:29:47 [TRACE] GRPCProvider: PlanResourceChange
2021/02/11 15:29:47 [TRACE] EvalWriteState: writing current state object for example_pet.pet_1
2021/02/11 15:29:47 [TRACE] EvalWriteDiff: recorded Create change for example_pet.pet_1
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": visit complete
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": dynamic subgraph completed successfully
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1": visit complete
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1 (expand)": dynamic subgraph completed successfully
2021/02/11 15:29:47 [TRACE] vertex "example_pet.pet_1 (expand)": visit complete
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "meta.count-boundary (EachMode fixup)"
2021/02/11 15:29:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2021/02/11 15:29:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "provider[\"example.com/example/example\"] (close)"
2021/02/11 15:29:47 [TRACE] vertex "provider[\"example.com/example/example\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2021/02/11 15:29:47 [TRACE] GRPCProvider: Close
2021-02-11T15:29:47.512-0800 [WARN]  plugin.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-02-11T15:29:47.514-0800 [DEBUG] plugin: plugin process exited: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 pid=19630
2021-02-11T15:29:47.514-0800 [DEBUG] plugin: plugin exited
2021/02/11 15:29:47 [TRACE] vertex "provider[\"example.com/example/example\"] (close)": visit complete
2021/02/11 15:29:47 [TRACE] dag/walk: visiting "root"
2021/02/11 15:29:47 [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2021/02/11 15:29:47 [TRACE] vertex "root": visit complete

An execution plan has been generated and is shown below.
Resource actions are indicated with the following symbols:
  + create

Terraform will perform the following actions:

  # example_pet.pet_1 will be created
  + resource "example_pet" "pet_1" {
      + id   = (known after apply)
      + name = "sharik"
    }

Plan: 1 to add, 0 to change, 0 to destroy.

2021/02/11 15:29:47 [DEBUG] command: asking for input: "Do you want to perform these actions?"
Do you want to perform these actions?
  Terraform will perform the actions described above.
  Only 'yes' will be accepted to approve.

  Enter a value: 
2021/02/11 15:29:52 [INFO] backend/local: apply calling Apply
2021/02/11 15:29:52 [INFO] terraform: building graph: GraphTypeApply
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.ConfigTransformer
2021/02/11 15:29:52 [TRACE] ConfigTransformer: Starting for path: 
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
  ------
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.LocalTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.OutputTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.DiffTransformer
2021/02/11 15:29:52 [TRACE] DiffTransformer starting
2021/02/11 15:29:52 [TRACE] DiffTransformer: found Create change for example_pet.pet_1 
2021/02/11 15:29:52 [TRACE] DiffTransformer: example_pet.pet_1 will be represented by example_pet.pet_1
2021/02/11 15:29:52 [TRACE] DiffTransformer complete
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.DiffTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
  ------
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021/02/11 15:29:52 [DEBUG] Resource state not found for node "example_pet.pet_1", instance example_pet.pet_1
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021/02/11 15:29:52 [TRACE] AttachResourceConfigTransformer: attaching to "example_pet.pet_1 (expand)" (*terraform.nodeExpandApplyableResource) config from pet.tf:11,1-31
2021/02/11 15:29:52 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to example_pet.pet_1 (expand)
2021/02/11 15:29:52 [TRACE] AttachResourceConfigTransformer: attaching to "example_pet.pet_1" (*terraform.NodeApplyableResourceInstance) config from pet.tf:11,1-31
2021/02/11 15:29:52 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to example_pet.pet_1
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.ProvisionerTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021/02/11 15:29:52 [TRACE] ProviderConfigTransformer: attaching to "provider[\"example.com/example/example\"]" provider configuration from pet.tf:1,1-19
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021/02/11 15:29:52 [TRACE] ProviderTransformer: exact match for provider["example.com/example/example"] serving example_pet.pet_1 (expand)
2021/02/11 15:29:52 [DEBUG] ProviderTransformer: "example_pet.pet_1 (expand)" (*terraform.nodeExpandApplyableResource) needs provider["example.com/example/example"]
2021/02/11 15:29:52 [TRACE] ProviderTransformer: exact match for provider["example.com/example/example"] serving example_pet.pet_1
2021/02/11 15:29:52 [DEBUG] ProviderTransformer: "example_pet.pet_1" (*terraform.NodeApplyableResourceInstance) needs provider["example.com/example/example"]
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2021/02/11 15:29:52 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021/02/11 15:29:52 [TRACE] AttachSchemaTransformer: attaching resource schema to example_pet.pet_1 (expand)
2021/02/11 15:29:52 [TRACE] AttachSchemaTransformer: attaching resource schema to example_pet.pet_1
2021/02/11 15:29:52 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["example.com/example/example"]
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021/02/11 15:29:52 [DEBUG] ReferenceTransformer: "provider[\"example.com/example/example\"]" references: []
2021/02/11 15:29:52 [DEBUG] ReferenceTransformer: "example_pet.pet_1 (expand)" references: []
2021/02/11 15:29:52 [DEBUG] ReferenceTransformer: "example_pet.pet_1" references: []
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021/02/11 15:29:52 [TRACE] AttachDependenciesTransformer: example_pet.pet_1 depends on []
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021/02/11 15:29:52 [TRACE] ForcedCBDTransformer: "example_pet.pet_1" (*terraform.NodeApplyableResourceInstance) has no CBD descendent, so skipping
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.DestroyEdgeTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.DestroyEdgeTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.CBDEdgeTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.CBDEdgeTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.pruneUnusedNodesTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.pruneUnusedNodesTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.TargetsTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  ------
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.CloseProvisionerTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.CloseProvisionerTransformer (no changes)
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
  ------
2021/02/11 15:29:52 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021/02/11 15:29:52 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
    example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
  example_pet.pet_1 (expand) - *terraform.nodeExpandApplyableResource
    provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
  provider["example.com/example/example"] - *terraform.NodeApplyableProvider
  provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
    example_pet.pet_1 - *terraform.NodeApplyableResourceInstance
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["example.com/example/example"] (close) - *terraform.graphNodeCloseProvider
  ------
2021/02/11 15:29:52 [DEBUG] Starting graph walk: walkApply
2021/02/11 15:29:52 [TRACE] dag/walk: visiting "provider[\"example.com/example/example\"]"
2021/02/11 15:29:52 [TRACE] vertex "provider[\"example.com/example/example\"]": starting visit (*terraform.NodeApplyableProvider)
2021-02-11T15:29:52.766-0800 [INFO]  plugin: configuring client automatic mTLS
2021-02-11T15:29:52.791-0800 [DEBUG] plugin: starting plugin: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 args=[/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1]
2021-02-11T15:29:52.796-0800 [DEBUG] plugin: plugin started: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 pid=19636
2021-02-11T15:29:52.796-0800 [DEBUG] plugin: waiting for RPC address: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1
2021-02-11T15:29:52.810-0800 [INFO]  plugin.terraform-provider-example_v0.0.1: configuring server automatic mTLS: timestamp=2021-02-11T15:29:52.809-0800
2021-02-11T15:29:52.838-0800 [DEBUG] plugin.terraform-provider-example_v0.0.1: plugin address: address=/var/folders/vt/vmymrq7d7194bv32slwtmdvh0000gn/T/plugin618234587 network=unix timestamp=2021-02-11T15:29:52.838-0800
2021-02-11T15:29:52.838-0800 [DEBUG] plugin: using plugin: version=5
2021/02/11 15:29:52 [TRACE] BuiltinEvalContext: Initialized "provider[\"example.com/example/example\"]" provider for provider["example.com/example/example"]
2021/02/11 15:29:52 [TRACE] buildProviderConfig for provider["example.com/example/example"]: using explicit config only
2021/02/11 15:29:52 [TRACE] GRPCProvider: GetSchema
2021-02-11T15:29:52.890-0800 [TRACE] plugin.stdio: waiting for stdio data
2021/02/11 15:29:52 [TRACE] GRPCProvider: Configure
2021/02/11 15:29:52 [TRACE] vertex "provider[\"example.com/example/example\"]": visit complete
2021/02/11 15:29:52 [TRACE] dag/walk: visiting "example_pet.pet_1 (expand)"
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1 (expand)": starting visit (*terraform.nodeExpandApplyableResource)
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1 (expand)": expanding dynamic subgraph
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1 (expand)": entering dynamic subgraph
2021/02/11 15:29:52 [TRACE] dag/walk: visiting "example_pet.pet_1"
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1": starting visit (*terraform.NodeApplyableResource)
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1": visit complete
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1 (expand)": dynamic subgraph completed successfully
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1 (expand)": visit complete
2021/02/11 15:29:52 [TRACE] dag/walk: visiting "example_pet.pet_1"
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1": starting visit (*terraform.NodeApplyableResourceInstance)
2021/02/11 15:29:52 [TRACE] EvalReadDiff: Read Create change from plan for example_pet.pet_1
2021/02/11 15:29:52 [TRACE] EvalReadState: reading state for example_pet.pet_1
2021/02/11 15:29:52 [TRACE] EvalReadState: no state present for example_pet.pet_1
2021/02/11 15:29:52 [TRACE] EvalReadDiff: Read Create change from plan for example_pet.pet_1
2021/02/11 15:29:52 [TRACE] Re-validating config for "example_pet.pet_1"
2021/02/11 15:29:52 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2021/02/11 15:29:52 [TRACE] GRPCProvider: PlanResourceChange
2021/02/11 15:29:52 [TRACE] EvalCheckPlannedChange: Verifying that actual change (action Create) matches planned change (action Create)
2021/02/11 15:29:52 [TRACE] EvalReadState: reading state for example_pet.pet_1
2021/02/11 15:29:52 [TRACE] EvalReadState: no state present for example_pet.pet_1
example_pet.pet_1: Creating...
2021/02/11 15:29:52 [DEBUG] EvalApply: ProviderMeta config value set
2021/02/11 15:29:52 [DEBUG] example_pet.pet_1: applying the planned Create change
2021/02/11 15:29:52 [TRACE] GRPCProvider: ApplyResourceChange
2021-02-11T15:29:52.895-0800 [INFO]  plugin.terraform-provider-example_v0.0.1: 2021/02/11 15:29:52 received invalid ID: 0: timestamp=2021-02-11T15:29:52.895-0800
2021/02/11 15:29:52 [WARN] EvalApply example_pet.pet_1: WARNING doing something weird here
2021/02/11 15:29:52 [TRACE] EvalWriteState: recording 0 dependencies for example_pet.pet_1
2021/02/11 15:29:52 [TRACE] EvalWriteState: writing current state object for example_pet.pet_1
2021/02/11 15:29:52 [TRACE] EvalWriteState: recording 0 dependencies for example_pet.pet_1
2021/02/11 15:29:52 [TRACE] EvalWriteState: writing current state object for example_pet.pet_1
example_pet.pet_1: Creation complete after 0s [id=4037200794235010051]
2021/02/11 15:29:52 [TRACE] statemgr.Filesystem: creating backup snapshot at terraform.tfstate.backup
2021/02/11 15:29:52 [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 37
2021/02/11 15:29:52 [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2021/02/11 15:29:52 [TRACE] vertex "example_pet.pet_1": visit complete
2021/02/11 15:29:52 [TRACE] dag/walk: visiting "meta.count-boundary (EachMode fixup)"
2021/02/11 15:29:52 [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2021/02/11 15:29:52 [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2021/02/11 15:29:52 [TRACE] dag/walk: visiting "provider[\"example.com/example/example\"] (close)"
2021/02/11 15:29:52 [TRACE] vertex "provider[\"example.com/example/example\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2021/02/11 15:29:52 [TRACE] GRPCProvider: Close
2021-02-11T15:29:52.931-0800 [WARN]  plugin.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-02-11T15:29:52.932-0800 [DEBUG] plugin: plugin process exited: path=/Users/cyrusjavan/.terraform.d/plugins/example.com/example/example/0.0.1/darwin_amd64/terraform-provider-example_v0.0.1 pid=19636
2021-02-11T15:29:52.932-0800 [DEBUG] plugin: plugin exited
2021/02/11 15:29:52 [TRACE] vertex "provider[\"example.com/example/example\"] (close)": visit complete
2021/02/11 15:29:52 [TRACE] dag/walk: visiting "root"
2021/02/11 15:29:52 [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2021/02/11 15:29:52 [TRACE] vertex "root": visit complete
2021/02/11 15:29:52 [TRACE] statemgr.Filesystem: have already backed up original terraform.tfstate to terraform.tfstate.backup on a previous write
2021/02/11 15:29:52 [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 38
2021/02/11 15:29:52 [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate

2021/02/11 15:29:52 [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
Apply complete! Resources: 1 added, 0 changed, 0 destroyed.
2021/02/11 15:29:52 [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock