sheax0r/gist:6a5b67ff6ef911a8e67f814752d29a71

## gistfile1.txt
2020/12/07 00:35:47 [INFO] Terraform version: 0.12.29
2020/12/07 00:35:47 [INFO] Go runtime version: go1.12.13
2020/12/07 00:35:47 [INFO] CLI args: []string{"/usr/local/bin/terraform", "apply"}
2020/12/07 00:35:47 [DEBUG] Attempting to open CLI config file: /root/.terraformrc
2020/12/07 00:35:47 [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2020/12/07 00:35:47 [DEBUG] checking for credentials in "/root/.terraform.d/plugins"
2020/12/07 00:35:47 [INFO] CLI command args: []string{"apply"}
2020/12/07 00:35:47 [WARN] Log levels other than TRACE are currently unreliable, and are supported only for backward compatibility.
  Use TF_LOG=TRACE to see Terraform's internal logs.
  ----
2020/12/07 00:35:47 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2020/12/07 00:35:47 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2020/12/07 00:35:47 [DEBUG] New state was assigned lineage "dbda29cb-165d-6646-55dd-90083bf5edd9"
2020/12/07 00:35:47 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2020/12/07 00:35:47 [TRACE] Meta.Backend: instantiated backend of type <nil>
2020/12/07 00:35:47 [DEBUG] checking for provider in "."
2020/12/07 00:35:47 [DEBUG] checking for provider in "/usr/local/bin"
2020/12/07 00:35:47 [DEBUG] checking for provider in ".terraform/plugins/linux_amd64"
2020/12/07 00:35:47 [DEBUG] found provider "terraform-provider-heroku_v3.1.0"
2020/12/07 00:35:47 [DEBUG] checking for provider in "/root/.terraform.d/plugins"
2020/12/07 00:35:47 [WARN] found legacy provider "terraform-provider-ion"
2020/12/07 00:35:47 [WARN] found legacy provider "terraform-provider-librato"
2020/12/07 00:35:47 [WARN] found legacy provider "terraform-provider-markmonitor"
2020/12/07 00:35:47 [WARN] found legacy provider "terraform-provider-splunk"
2020/12/07 00:35:47 [DEBUG] found valid plugin: "markmonitor", "0.0.0", "/root/.terraform.d/plugins/terraform-provider-markmonitor"
2020/12/07 00:35:47 [DEBUG] found valid plugin: "splunk", "0.0.0", "/root/.terraform.d/plugins/terraform-provider-splunk"
2020/12/07 00:35:47 [DEBUG] found valid plugin: "heroku", "3.1.0", "/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0"
2020/12/07 00:35:47 [DEBUG] found valid plugin: "ion", "0.0.0", "/root/.terraform.d/plugins/terraform-provider-ion"
2020/12/07 00:35:47 [DEBUG] found valid plugin: "librato", "0.0.0", "/root/.terraform.d/plugins/terraform-provider-librato"
2020/12/07 00:35:47 [DEBUG] checking for provisioner in "."
2020/12/07 00:35:47 [DEBUG] checking for provisioner in "/usr/local/bin"
2020/12/07 00:35:47 [DEBUG] checking for provisioner in ".terraform/plugins/linux_amd64"
2020/12/07 00:35:47 [DEBUG] checking for provisioner in "/root/.terraform.d/plugins"
2020/12/07 00:35:47 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2020/12/07 00:35:47 [INFO] backend/local: starting Apply operation
2020/12/07 00:35:47 [TRACE] backend/local: requesting state manager for workspace "default"
2020/12/07 00:35: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
2020/12/07 00:35:47 [TRACE] backend/local: requesting state lock for workspace "default"
2020/12/07 00:35:47 [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2020/12/07 00:35:47 [TRACE] statemgr.Filesystem: existing snapshot has lineage "8573af63-a0c1-3fad-d525-7c11ae508362" serial 5
2020/12/07 00:35:47 [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2020/12/07 00:35:47 [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2020/12/07 00:35:47 [TRACE] backend/local: reading remote state for workspace "default"
2020/12/07 00:35:47 [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2020/12/07 00:35:47 [TRACE] statemgr.Filesystem: read snapshot with lineage "8573af63-a0c1-3fad-d525-7c11ae508362" serial 5
2020/12/07 00:35:47 [TRACE] backend/local: retrieving local state snapshot for workspace "default"
2020/12/07 00:35:47 [TRACE] backend/local: building context for current working directory
2020/12/07 00:35:47 [TRACE] terraform.NewContext: starting
2020/12/07 00:35:47 [TRACE] terraform.NewContext: resolving provider version selections
2020/12/07 00:35:47 [TRACE] terraform.NewContext: loading provider schemas
2020/12/07 00:35:47 [TRACE] LoadSchemas: retrieving schema for provider type "heroku"
2020-12-07T00:35:47.247Z [INFO]  plugin: configuring client automatic mTLS
2020-12-07T00:35:47.283Z [DEBUG] plugin: starting plugin: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 args=[/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0]
2020-12-07T00:35:47.284Z [DEBUG] plugin: plugin started: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 pid=1438
2020-12-07T00:35:47.284Z [DEBUG] plugin: waiting for RPC address: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0
2020-12-07T00:35:47.302Z [INFO]  plugin.terraform-provider-heroku_v3.1.0: configuring server automatic mTLS: timestamp=2020-12-07T00:35:47.302Z
2020-12-07T00:35:47.336Z [DEBUG] plugin: using plugin: version=5
2020-12-07T00:35:47.336Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: plugin address: address=/tmp/plugin877679789 network=unix timestamp=2020-12-07T00:35:47.336Z
2020/12/07 00:35:47 [TRACE] GRPCProvider: GetSchema
2020-12-07T00:35:47.410Z [TRACE] plugin.stdio: waiting for stdio data
2020/12/07 00:35:47 [TRACE] GRPCProvider: Close
2020-12-07T00:35:47.415Z [WARN]  plugin.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2020-12-07T00:35:47.418Z [DEBUG] plugin: plugin process exited: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 pid=1438
2020-12-07T00:35:47.419Z [DEBUG] plugin: plugin exited
2020/12/07 00:35:47 [TRACE] terraform.NewContext: complete
2020/12/07 00:35:47 [TRACE] backend/local: finished building terraform.Context
2020/12/07 00:35:47 [TRACE] backend/local: requesting interactive input, if necessary
2020/12/07 00:35:47 [TRACE] Context.Input: Prompting for provider arguments
2020/12/07 00:35:47 [TRACE] Context.Input: Provider provider.heroku implied by resource block at main.tf:1,1-30
2020/12/07 00:35:47 [TRACE] Context.Input: Input for provider.heroku: map[string]cty.Value{}
2020/12/07 00:35:47 [TRACE] backend/local: running validation operation
2020/12/07 00:35:47 [INFO] terraform: building graph: GraphTypeValidate
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ConfigTransformer
2020/12/07 00:35:47 [TRACE] ConfigTransformer: Starting for path:
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.LocalTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OutputTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.StateTransformer
2020/12/07 00:35:47 [TRACE] StateTransformer: state is empty, so nothing to do
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2020/12/07 00:35:47 [TRACE] AttachResourceConfigTransformer: attaching to "heroku_app.myapp" (*terraform.NodeValidatableResource) config from main.tf:1,1-30
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ProvisionerTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
  ------
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2020/12/07 00:35:47 [DEBUG] adding implicit provider configuration provider.heroku, implied first by heroku_app.myapp
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2020/12/07 00:35:47 [TRACE] ProviderTransformer: heroku_app.myapp is provided by provider.heroku or inherited equivalent
2020/12/07 00:35:47 [TRACE] ProviderTransformer: exact match for provider.heroku serving heroku_app.myapp
2020/12/07 00:35:47 [DEBUG] ProviderTransformer: "heroku_app.myapp" (*terraform.NodeValidatableResource) needs provider.heroku
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2020/12/07 00:35:47 [TRACE] AttachSchemaTransformer: attaching resource schema to heroku_app.myapp
2020/12/07 00:35:47 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.heroku
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2020/12/07 00:35:47 [DEBUG] ReferenceTransformer: "heroku_app.myapp" references: []
2020/12/07 00:35:47 [DEBUG] ReferenceTransformer: "provider.heroku" references: []
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.TargetsTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.CloseProvisionerTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.CloseProvisionerTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RootTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  root - terraform.graphNodeRoot
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider.heroku (close) - *terraform.graphNodeCloseProvider
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  heroku_app.myapp - *terraform.NodeValidatableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodeValidatableResource
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodeValidatableResource
  root - terraform.graphNodeRoot
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider.heroku (close) - *terraform.graphNodeCloseProvider
  ------
2020/12/07 00:35:47 [DEBUG] Starting graph walk: walkValidate
2020/12/07 00:35:47 [TRACE] dag/walk: updating graph
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "provider.heroku"
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "meta.count-boundary (EachMode fixup)"
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "provider.heroku (close)"
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "root"
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "provider.heroku (close)" waiting on "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "root" waiting on "meta.count-boundary (EachMode fixup)"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "root" waiting on "provider.heroku (close)"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "heroku_app.myapp" waiting on "provider.heroku"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "meta.count-boundary (EachMode fixup)" waiting on "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] dag/walk: dependencies changed for "provider.heroku (close)", sending new deps
2020/12/07 00:35:47 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2020/12/07 00:35:47 [TRACE] dag/walk: dependencies changed for "heroku_app.myapp", sending new deps
2020/12/07 00:35:47 [TRACE] dag/walk: dependencies changed for "meta.count-boundary (EachMode fixup)", sending new deps
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "provider.heroku"
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku": starting visit (*terraform.NodeApplyableProvider)
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku": evaluating
2020/12/07 00:35:47 [TRACE] [walkValidate] Entering eval tree: provider.heroku
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalInitProvider
2020-12-07T00:35:47.427Z [INFO]  plugin: configuring client automatic mTLS
2020-12-07T00:35:47.461Z [DEBUG] plugin: starting plugin: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 args=[/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0]
2020-12-07T00:35:47.463Z [DEBUG] plugin: plugin started: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 pid=1450
2020-12-07T00:35:47.463Z [DEBUG] plugin: waiting for RPC address: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0
2020-12-07T00:35:47.484Z [INFO]  plugin.terraform-provider-heroku_v3.1.0: configuring server automatic mTLS: timestamp=2020-12-07T00:35:47.484Z
2020-12-07T00:35:47.533Z [DEBUG] plugin: using plugin: version=5
2020-12-07T00:35:47.533Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: plugin address: address=/tmp/plugin053850253 network=unix timestamp=2020-12-07T00:35:47.533Z
2020/12/07 00:35:47 [TRACE] BuiltinEvalContext: Initialized "heroku" provider for provider.heroku
2020/12/07 00:35:47 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalOpFilter
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalGetProvider
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalValidateProvider
2020/12/07 00:35:47 [TRACE] buildProviderConfig for provider.heroku: no configuration at all
2020/12/07 00:35:47 [TRACE] GRPCProvider: GetSchema
2020-12-07T00:35:47.607Z [TRACE] plugin.stdio: waiting for stdio data
2020/12/07 00:35:47 [TRACE] GRPCProvider: PrepareProviderConfig
2020/12/07 00:35:47 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:47 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:47 [TRACE] [walkValidate] Exiting eval tree: provider.heroku
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku": visit complete
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": starting visit (*terraform.NodeValidatableResource)
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": evaluating
2020/12/07 00:35:47 [TRACE] [walkValidate] Entering eval tree: heroku_app.myapp
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalGetProvider
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalValidateResource
2020/12/07 00:35:47 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2020/12/07 00:35:47 [TRACE] [walkValidate] Exiting eval tree: heroku_app.myapp
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": visit complete
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "provider.heroku (close)"
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku (close)": starting visit (*terraform.graphNodeCloseProvider)
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "meta.count-boundary (EachMode fixup)"
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku (close)": evaluating
2020/12/07 00:35:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2020/12/07 00:35:47 [TRACE] [walkValidate] Entering eval tree: provider.heroku (close)
2020/12/07 00:35:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": evaluating
2020/12/07 00:35:47 [TRACE] [walkValidate] Entering eval tree: meta.count-boundary (EachMode fixup)
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalCloseProvider
2020/12/07 00:35:47 [TRACE] GRPCProvider: Close
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalCountFixZeroOneBoundaryGlobal
2020/12/07 00:35:47 [TRACE] [walkValidate] Exiting eval tree: meta.count-boundary (EachMode fixup)
2020/12/07 00:35:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2020-12-07T00:35:47.616Z [WARN]  plugin.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2020-12-07T00:35:47.621Z [DEBUG] plugin: plugin process exited: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 pid=1450
2020-12-07T00:35:47.621Z [DEBUG] plugin: plugin exited
2020/12/07 00:35:47 [TRACE] [walkValidate] Exiting eval tree: provider.heroku (close)
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku (close)": visit complete
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "root"
2020/12/07 00:35:47 [TRACE] vertex "root": starting visit (terraform.graphNodeRoot)
2020/12/07 00:35:47 [TRACE] vertex "root": visit complete
2020/12/07 00:35:47 [INFO] backend/local: apply calling Refresh
2020/12/07 00:35:47 [INFO] terraform: building graph: GraphTypeRefresh
2020/12/07 00:35:47 [TRACE] No managed resources in state during refresh; skipping managed resource transformer
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ConfigTransformer
2020/12/07 00:35:47 [TRACE] ConfigTransformer: Starting for path:
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ConfigTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.StateTransformer
2020/12/07 00:35:47 [TRACE] StateTransformer: state is empty, so nothing to do
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.LocalTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OutputTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer (no changes)
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.graphTransformerMulti (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.TargetsTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.CloseProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RootTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
  root - terraform.graphNodeRoot
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer (no changes)
2020/12/07 00:35:47 [DEBUG] Starting graph walk: walkRefresh
2020/12/07 00:35:47 [TRACE] dag/walk: updating graph
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "root"
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "root"
2020/12/07 00:35:47 [TRACE] vertex "root": starting visit (terraform.graphNodeRoot)
2020/12/07 00:35:47 [TRACE] vertex "root": visit complete
2020/12/07 00:35:47 [INFO] backend/local: apply calling Plan
2020/12/07 00:35:47 [INFO] terraform: building graph: GraphTypePlan
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ConfigTransformer
2020/12/07 00:35:47 [TRACE] ConfigTransformer: Starting for path:
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.LocalTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OutputTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.StateTransformer
2020/12/07 00:35:47 [TRACE] StateTransformer: state is empty, so nothing to do
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2020/12/07 00:35:47 [TRACE] AttachResourceConfigTransformer: attaching to "heroku_app.myapp" (*terraform.NodePlannableResource) config from main.tf:1,1-30
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ProvisionerTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
  ------
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2020/12/07 00:35:47 [DEBUG] adding implicit provider configuration provider.heroku, implied first by heroku_app.myapp
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2020/12/07 00:35:47 [TRACE] ProviderTransformer: heroku_app.myapp is provided by provider.heroku or inherited equivalent
2020/12/07 00:35:47 [TRACE] ProviderTransformer: exact match for provider.heroku serving heroku_app.myapp
2020/12/07 00:35:47 [DEBUG] ProviderTransformer: "heroku_app.myapp" (*terraform.NodePlannableResource) needs provider.heroku
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2020/12/07 00:35:47 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2020/12/07 00:35:47 [TRACE] AttachSchemaTransformer: attaching resource schema to heroku_app.myapp
2020/12/07 00:35:47 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.heroku
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2020/12/07 00:35:47 [DEBUG] ReferenceTransformer: "heroku_app.myapp" references: []
2020/12/07 00:35:47 [DEBUG] ReferenceTransformer: "provider.heroku" references: []
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.TargetsTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2020/12/07 00:35:47 [TRACE] ForcedCBDTransformer: "heroku_app.myapp" (*terraform.NodePlannableResource) has no CBD descendent, so skipping
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.CloseProvisionerTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.CloseProvisionerTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RootTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  root - terraform.graphNodeRoot
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider.heroku (close) - *terraform.graphNodeCloseProvider
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodePlannableResource
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodePlannableResource
  root - terraform.graphNodeRoot
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider.heroku (close) - *terraform.graphNodeCloseProvider
  ------
2020/12/07 00:35:47 [DEBUG] Starting graph walk: walkPlan
2020/12/07 00:35:47 [TRACE] dag/walk: updating graph
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "provider.heroku (close)"
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "root"
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "provider.heroku"
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "meta.count-boundary (EachMode fixup)"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "root" waiting on "provider.heroku (close)"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "heroku_app.myapp" waiting on "provider.heroku"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "meta.count-boundary (EachMode fixup)" waiting on "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "provider.heroku (close)" waiting on "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] dag/walk: added edge: "root" waiting on "meta.count-boundary (EachMode fixup)"
2020/12/07 00:35:47 [TRACE] dag/walk: dependencies changed for "provider.heroku (close)", sending new deps
2020/12/07 00:35:47 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2020/12/07 00:35:47 [TRACE] dag/walk: dependencies changed for "heroku_app.myapp", sending new deps
2020/12/07 00:35:47 [TRACE] dag/walk: dependencies changed for "meta.count-boundary (EachMode fixup)", sending new deps
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "provider.heroku"
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku": starting visit (*terraform.NodeApplyableProvider)
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku": evaluating
2020/12/07 00:35:47 [TRACE] [walkPlan] Entering eval tree: provider.heroku
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalInitProvider
2020-12-07T00:35:47.634Z [INFO]  plugin: configuring client automatic mTLS
2020-12-07T00:35:47.673Z [DEBUG] plugin: starting plugin: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 args=[/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0]
2020-12-07T00:35:47.676Z [DEBUG] plugin: plugin started: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 pid=1460
2020-12-07T00:35:47.676Z [DEBUG] plugin: waiting for RPC address: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0
2020-12-07T00:35:47.691Z [INFO]  plugin.terraform-provider-heroku_v3.1.0: configuring server automatic mTLS: timestamp=2020-12-07T00:35:47.691Z
2020-12-07T00:35:47.726Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: plugin address: network=unix address=/tmp/plugin137966435 timestamp=2020-12-07T00:35:47.725Z
2020-12-07T00:35:47.726Z [DEBUG] plugin: using plugin: version=5
2020/12/07 00:35:47 [TRACE] BuiltinEvalContext: Initialized "heroku" provider for provider.heroku
2020/12/07 00:35:47 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:47 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalOpFilter
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalGetProvider
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalOpFilter
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalConfigProvider
2020/12/07 00:35:47 [TRACE] buildProviderConfig for provider.heroku: no configuration at all
2020/12/07 00:35:47 [TRACE] GRPCProvider: GetSchema
2020-12-07T00:35:47.798Z [TRACE] plugin.stdio: waiting for stdio data
2020/12/07 00:35:47 [TRACE] GRPCProvider: Configure
2020-12-07T00:35:47.801Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:47 [INFO] Initializing Heroku provider
2020/12/07 00:35:47 [TRACE] [walkPlan] Exiting eval tree: provider.heroku
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku": visit complete
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": starting visit (*terraform.NodePlannableResource)
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": evaluating
2020/12/07 00:35:47 [TRACE] [walkPlan] Entering eval tree: heroku_app.myapp
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalWriteResourceState
2020/12/07 00:35:47 [TRACE] [walkPlan] Exiting eval tree: heroku_app.myapp
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": expanding dynamic subgraph
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
  heroku_app.myapp - *terraform.NodePlannableResourceInstance
  ------
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.OrphanResourceCountTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.OrphanResourceCountTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2020/12/07 00:35:47 [DEBUG] Resource instance state not found for node "heroku_app.myapp", instance heroku_app.myapp
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.TargetsTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2020-12-07T00:35:47.804Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:47 [INFO] Heroku Client configured for user: michael.shea@heroku.com
2020-12-07T00:35:47.805Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:47 [DEBUG] Heroku provider initialized: {APIKey:xxx Email:michael.shea@heroku.com URL:https://api.heroku.com Headers:xxx DebugHTTP:true PostAppCreateDelay:5 PostDomainCreateDelay:5 PostSpaceCreateDelay:5}
2020/12/07 00:35:47 [DEBUG] ReferenceTransformer: "heroku_app.myapp" references: []
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2020/12/07 00:35:47 [TRACE] Executing graph transform *terraform.RootTransformer
2020/12/07 00:35:47 [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": entering dynamic subgraph
2020/12/07 00:35:47 [TRACE] dag/walk: updating graph
2020/12/07 00:35:47 [TRACE] dag/walk: added new vertex: "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": starting visit (*terraform.NodePlannableResourceInstance)
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": evaluating
2020/12/07 00:35:47 [TRACE] [walkPlan] Entering eval tree: heroku_app.myapp
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalGetProvider
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalReadState
2020/12/07 00:35:47 [TRACE] EvalReadState: reading state for heroku_app.myapp
2020/12/07 00:35:47 [TRACE] EvalReadState: no state present for heroku_app.myapp
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalValidateSelfRef
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalDiff
2020/12/07 00:35:47 [TRACE] Re-validating config for "heroku_app.myapp"
2020/12/07 00:35:47 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2020/12/07 00:35:47 [TRACE] GRPCProvider: PlanResourceChange
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalCheckPreventDestroy
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalWriteState
2020/12/07 00:35:47 [TRACE] EvalWriteState: writing current state object for heroku_app.myapp
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalWriteDiff
2020/12/07 00:35:47 [TRACE] EvalWriteDiff: recorded Create change for heroku_app.myapp
2020/12/07 00:35:47 [TRACE] [walkPlan] Exiting eval tree: heroku_app.myapp
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": visit complete
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": dynamic subgraph completed successfully
2020/12/07 00:35:47 [TRACE] vertex "heroku_app.myapp": visit complete
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "provider.heroku (close)"
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku (close)": starting visit (*terraform.graphNodeCloseProvider)
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku (close)": evaluating
2020/12/07 00:35:47 [TRACE] [walkPlan] Entering eval tree: provider.heroku (close)
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalCloseProvider
2020/12/07 00:35:47 [TRACE] GRPCProvider: Close
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "meta.count-boundary (EachMode fixup)"
2020/12/07 00:35:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2020/12/07 00:35:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": evaluating
2020/12/07 00:35:47 [TRACE] [walkPlan] Entering eval tree: meta.count-boundary (EachMode fixup)
2020/12/07 00:35:47 [TRACE] <root>: eval: *terraform.EvalCountFixZeroOneBoundaryGlobal
2020/12/07 00:35:47 [TRACE] [walkPlan] Exiting eval tree: meta.count-boundary (EachMode fixup)
2020/12/07 00:35:47 [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2020-12-07T00:35:47.813Z [WARN]  plugin.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2020-12-07T00:35:47.817Z [DEBUG] plugin: plugin process exited: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 pid=1460
2020-12-07T00:35:47.818Z [DEBUG] plugin: plugin exited
2020/12/07 00:35:47 [TRACE] [walkPlan] Exiting eval tree: provider.heroku (close)
2020/12/07 00:35:47 [TRACE] vertex "provider.heroku (close)": visit complete
2020/12/07 00:35:47 [TRACE] dag/walk: visiting "root"
2020/12/07 00:35:47 [TRACE] vertex "root": starting visit (terraform.graphNodeRoot)
2020/12/07 00:35:47 [TRACE] vertex "root": visit complete
2020/12/07 00:35:47 [DEBUG] command: asking for input: "Do you want to perform these actions?"
2020/12/07 00:35:50 [INFO] backend/local: apply calling Apply
2020/12/07 00:35:50 [INFO] terraform: building graph: GraphTypeApply
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.ConfigTransformer
2020/12/07 00:35:50 [TRACE] ConfigTransformer: Starting for path:
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
  ------
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.DiffTransformer
2020/12/07 00:35:50 [TRACE] DiffTransformer starting
2020/12/07 00:35:50 [TRACE] DiffTransformer: found Create change for heroku_app.myapp
2020/12/07 00:35:50 [TRACE] DiffTransformer: heroku_app.myapp will be represented by heroku_app.myapp
2020/12/07 00:35:50 [TRACE] DiffTransformer complete
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.DiffTransformer with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
  ------
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.OrphanResourceTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.OrphanResourceTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2020/12/07 00:35:50 [TRACE] AttachResourceConfigTransformer: attaching to "heroku_app.myapp (prepare state)" (*terraform.NodeApplyableResource) config from main.tf:1,1-30
2020/12/07 00:35:50 [TRACE] AttachResourceConfigTransformer: attaching to "heroku_app.myapp" (*terraform.NodeApplyableResourceInstance) config from main.tf:1,1-30
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2020/12/07 00:35:50 [DEBUG] Resource state not found for node "heroku_app.myapp", instance heroku_app.myapp
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.ProvisionerTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.LocalTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.OutputTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
  ------
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2020/12/07 00:35:50 [DEBUG] adding implicit provider configuration provider.heroku, implied first by heroku_app.myapp (prepare state)
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2020/12/07 00:35:50 [TRACE] ProviderTransformer: heroku_app.myapp (prepare state) is provided by provider.heroku or inherited equivalent
2020/12/07 00:35:50 [TRACE] ProviderTransformer: heroku_app.myapp is provided by provider.heroku or inherited equivalent
2020/12/07 00:35:50 [TRACE] ProviderTransformer: exact match for provider.heroku serving heroku_app.myapp (prepare state)
2020/12/07 00:35:50 [DEBUG] ProviderTransformer: "heroku_app.myapp (prepare state)" (*terraform.NodeApplyableResource) needs provider.heroku
2020/12/07 00:35:50 [TRACE] ProviderTransformer: exact match for provider.heroku serving heroku_app.myapp
2020/12/07 00:35:50 [DEBUG] ProviderTransformer: "heroku_app.myapp" (*terraform.NodeApplyableResourceInstance) needs provider.heroku
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2020/12/07 00:35:50 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2020/12/07 00:35:50 [TRACE] AttachSchemaTransformer: attaching resource schema to heroku_app.myapp (prepare state)
2020/12/07 00:35:50 [TRACE] AttachSchemaTransformer: attaching resource schema to heroku_app.myapp
2020/12/07 00:35:50 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.heroku
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2020/12/07 00:35:50 [DEBUG] ReferenceTransformer: "heroku_app.myapp (prepare state)" references: []
2020/12/07 00:35:50 [DEBUG] ReferenceTransformer: "heroku_app.myapp" references: []
2020/12/07 00:35:50 [DEBUG] ReferenceTransformer: "provider.heroku" references: []
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2020/12/07 00:35:50 [TRACE] AttachDependenciesTransformer: heroku_app.myapp depends on []
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.DestroyEdgeTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.DestroyEdgeTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.CBDEdgeTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.CBDEdgeTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.PruneUnusedValuesTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.PruneUnusedValuesTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.TargetsTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  ------
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.CloseProvisionerTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.CloseProvisionerTransformer (no changes)
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.RootTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  root - terraform.graphNodeRoot
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider.heroku (close) - *terraform.graphNodeCloseProvider
  ------
2020/12/07 00:35:50 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2020/12/07 00:35:50 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  heroku_app.myapp - *terraform.NodeApplyableResourceInstance
    heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
  heroku_app.myapp (prepare state) - *terraform.NodeApplyableResource
    provider.heroku - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    heroku_app.myapp - *terraform.NodeApplyableResourceInstance
  provider.heroku - *terraform.NodeApplyableProvider
  provider.heroku (close) - *terraform.graphNodeCloseProvider
    heroku_app.myapp - *terraform.NodeApplyableResourceInstance
  root - terraform.graphNodeRoot
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider.heroku (close) - *terraform.graphNodeCloseProvider
  ------
2020/12/07 00:35:50 [DEBUG] Starting graph walk: walkApply
2020/12/07 00:35:50 [TRACE] dag/walk: updating graph
2020/12/07 00:35:50 [TRACE] dag/walk: added new vertex: "root"
2020/12/07 00:35:50 [TRACE] dag/walk: added new vertex: "heroku_app.myapp (prepare state)"
2020/12/07 00:35:50 [TRACE] dag/walk: added new vertex: "heroku_app.myapp"
2020/12/07 00:35:50 [TRACE] dag/walk: added new vertex: "provider.heroku"
2020/12/07 00:35:50 [TRACE] dag/walk: added new vertex: "meta.count-boundary (EachMode fixup)"
2020/12/07 00:35:50 [TRACE] dag/walk: added new vertex: "provider.heroku (close)"
2020/12/07 00:35:50 [TRACE] dag/walk: added edge: "root" waiting on "meta.count-boundary (EachMode fixup)"
2020/12/07 00:35:50 [TRACE] dag/walk: added edge: "heroku_app.myapp" waiting on "heroku_app.myapp (prepare state)"
2020/12/07 00:35:50 [TRACE] dag/walk: added edge: "root" waiting on "provider.heroku (close)"
2020/12/07 00:35:50 [TRACE] dag/walk: added edge: "heroku_app.myapp (prepare state)" waiting on "provider.heroku"
2020/12/07 00:35:50 [TRACE] dag/walk: added edge: "meta.count-boundary (EachMode fixup)" waiting on "heroku_app.myapp"
2020/12/07 00:35:50 [TRACE] dag/walk: added edge: "provider.heroku (close)" waiting on "heroku_app.myapp"
2020/12/07 00:35:50 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2020/12/07 00:35:50 [TRACE] dag/walk: dependencies changed for "heroku_app.myapp", sending new deps
2020/12/07 00:35:50 [TRACE] dag/walk: dependencies changed for "heroku_app.myapp (prepare state)", sending new deps
2020/12/07 00:35:50 [TRACE] dag/walk: dependencies changed for "meta.count-boundary (EachMode fixup)", sending new deps
2020/12/07 00:35:50 [TRACE] dag/walk: dependencies changed for "provider.heroku (close)", sending new deps
2020/12/07 00:35:50 [TRACE] dag/walk: visiting "provider.heroku"
2020/12/07 00:35:50 [TRACE] vertex "provider.heroku": starting visit (*terraform.NodeApplyableProvider)
2020/12/07 00:35:50 [TRACE] vertex "provider.heroku": evaluating
2020/12/07 00:35:50 [TRACE] [walkApply] Entering eval tree: provider.heroku
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalInitProvider
2020-12-07T00:35:50.267Z [INFO]  plugin: configuring client automatic mTLS
2020-12-07T00:35:50.302Z [DEBUG] plugin: starting plugin: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 args=[/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0]
2020-12-07T00:35:50.303Z [DEBUG] plugin: plugin started: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 pid=1471
2020-12-07T00:35:50.304Z [DEBUG] plugin: waiting for RPC address: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0
2020-12-07T00:35:50.318Z [INFO]  plugin.terraform-provider-heroku_v3.1.0: configuring server automatic mTLS: timestamp=2020-12-07T00:35:50.318Z
2020-12-07T00:35:50.353Z [DEBUG] plugin: using plugin: version=5
2020-12-07T00:35:50.353Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: plugin address: address=/tmp/plugin836967234 network=unix timestamp=2020-12-07T00:35:50.352Z
2020/12/07 00:35:50 [TRACE] BuiltinEvalContext: Initialized "heroku" provider for provider.heroku
2020/12/07 00:35:50 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:50 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalOpFilter
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalGetProvider
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalOpFilter
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalConfigProvider
2020/12/07 00:35:50 [TRACE] buildProviderConfig for provider.heroku: no configuration at all
2020/12/07 00:35:50 [TRACE] GRPCProvider: GetSchema
2020-12-07T00:35:50.422Z [TRACE] plugin.stdio: waiting for stdio data
2020/12/07 00:35:50 [TRACE] GRPCProvider: Configure
2020-12-07T00:35:50.426Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [INFO] Initializing Heroku provider
2020-12-07T00:35:50.428Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [INFO] Heroku Client configured for user: michael.shea@heroku.com
2020-12-07T00:35:50.429Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [DEBUG] Heroku provider initialized: {APIKey:xxx Email:michael.shea@heroku.com URL:https://api.heroku.com Headers:xxx DebugHTTP:true PostAppCreateDelay:5 PostDomainCreateDelay:5 PostSpaceCreateDelay:5}
2020/12/07 00:35:50 [TRACE] [walkApply] Exiting eval tree: provider.heroku
2020/12/07 00:35:50 [TRACE] vertex "provider.heroku": visit complete
2020/12/07 00:35:50 [TRACE] dag/walk: visiting "heroku_app.myapp (prepare state)"
2020/12/07 00:35:50 [TRACE] vertex "heroku_app.myapp (prepare state)": starting visit (*terraform.NodeApplyableResource)
2020/12/07 00:35:50 [TRACE] vertex "heroku_app.myapp (prepare state)": evaluating
2020/12/07 00:35:50 [TRACE] [walkApply] Entering eval tree: heroku_app.myapp (prepare state)
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalWriteResourceState
2020/12/07 00:35:50 [TRACE] [walkApply] Exiting eval tree: heroku_app.myapp (prepare state)
2020/12/07 00:35:50 [TRACE] vertex "heroku_app.myapp (prepare state)": visit complete
2020/12/07 00:35:50 [TRACE] dag/walk: visiting "heroku_app.myapp"
2020/12/07 00:35:50 [TRACE] vertex "heroku_app.myapp": starting visit (*terraform.NodeApplyableResourceInstance)
2020/12/07 00:35:50 [TRACE] vertex "heroku_app.myapp": evaluating
2020/12/07 00:35:50 [TRACE] [walkApply] Entering eval tree: heroku_app.myapp
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalSequence
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalGetProvider
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalReadDiff
2020/12/07 00:35:50 [TRACE] EvalReadDiff: Read Create change from plan for heroku_app.myapp
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalIf
2020/12/07 00:35:50 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalIf
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalReadState
2020/12/07 00:35:50 [TRACE] EvalReadState: reading state for heroku_app.myapp
2020/12/07 00:35:50 [TRACE] EvalReadState: no state present for heroku_app.myapp
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalReadDiff
2020/12/07 00:35:50 [TRACE] EvalReadDiff: Read Create change from plan for heroku_app.myapp
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalDiff
2020/12/07 00:35:50 [TRACE] Re-validating config for "heroku_app.myapp"
2020/12/07 00:35:50 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2020/12/07 00:35:50 [TRACE] GRPCProvider: PlanResourceChange
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalCheckPlannedChange
2020/12/07 00:35:50 [TRACE] EvalCheckPlannedChange: Verifying that actual change (action Create) matches planned change (action Create)
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalGetProvider
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalReadState
2020/12/07 00:35:50 [TRACE] EvalReadState: reading state for heroku_app.myapp
2020/12/07 00:35:50 [TRACE] EvalReadState: no state present for heroku_app.myapp
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalReduceDiff
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalIf
2020/12/07 00:35:50 [TRACE] <root>: eval: terraform.EvalNoop
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalApplyPre
2020/12/07 00:35:50 [TRACE] <root>: eval: *terraform.EvalApply
2020/12/07 00:35:50 [DEBUG] heroku_app.myapp: applying the planned Create change
2020/12/07 00:35:50 [TRACE] GRPCProvider: ApplyResourceChange
2020-12-07T00:35:50.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [DEBUG] setting computed for "all_config_vars" from ComputedKeys
2020-12-07T00:35:50.439Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [DEBUG] setting computed for "buildpacks" from ComputedKeys
2020-12-07T00:35:50.439Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [DEBUG] setting computed for "config_vars" from ComputedKeys
2020-12-07T00:35:50.439Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [DEBUG] setting computed for "sensitive_config_vars" from ComputedKeys
2020-12-07T00:35:50.439Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [DEBUG] App name: sheatest21123
2020-12-07T00:35:50.440Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [DEBUG] App region: us
2020-12-07T00:35:50.440Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 [DEBUG] Creating Heroku app...
2020-12-07T00:35:50.443Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:50 POST /apps HTTP/1.1
2020-12-07T00:35:50.443Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Host: api.heroku.com
2020-12-07T00:35:50.443Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: User-Agent: heroku/v5 (linux; amd64) terraform-provider-heroku/dev
2020-12-07T00:35:50.443Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Content-Length: 38
2020-12-07T00:35:50.443Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Accept: application/vnd.heroku+json; version=3
2020-12-07T00:35:50.444Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Authorization: Basic ***REDACTED***
2020-12-07T00:35:50.444Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Content-Type: application/json
2020-12-07T00:35:50.444Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Request-Id: 37d9c8ef-756e-4313-99e9-9923714884d5
2020-12-07T00:35:50.444Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Accept-Encoding: gzip
2020-12-07T00:35:50.444Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0:
2020-12-07T00:35:50.444Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: {"name":"sheatest21123","region":"us"}
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:51 HTTP/2.0 201 Created
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Cache-Control: private, no-cache
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Content-Expansion: region
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Content-Type: application/json
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Date: Mon, 07 Dec 2020 00:35:52 GMT
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Location: https://sheatest21123.herokuapp.com/
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Oauth-Scope: global
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Oauth-Scope-Accepted: create-apps global write write-protected
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Ratelimit-Multiplier: 1
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Ratelimit-Remaining: 4499
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Request-Id: b47e2443-32a3-4de1-ab8d-9f847de74be3,37d9c8ef-756e-4313-99e9-9923714884d5,ad70c1d1-f9b2-8f34-ad83-7f77cb1c1303,c4b70e49-37a3-0ba3-47d5-86d2049a6d16
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Vary: Authorization,Accept-Encoding
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Via: 1.1 spaces-router (c9d446e9ac4c), 2.0 spaces-router (c9d446e9ac4c)
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: X-Content-Type-Options: nosniff
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: X-Runtime: 0.234733666
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0:
2020-12-07T00:35:51.996Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: {"acm":false,"archived_at":null,"buildpack_provided_description":null,"build_stack":{"id":"69bee368-352b-4bd0-9b7c-819d860a2588","name":"heroku-18"},"created_at":"2020-12-07T00:35:52Z","id":"6c7e3769-fa83-4357-a867-5bf34e4252f8","git_url":"https://git.heroku.com/sheatest21123.git","maintenance":false,"name":"sheatest21123","owner":{"email":"michael.shea@heroku.com","id":"171650cf-296b-4446-abd6-f8a29e6efdbd"},"region":{"id":"59accabd-516d-4f0e-83e6-6e3757701145","name":"us"},"organization":null,"team":null,"space":null,"internal_routing":null,"released_at":"2020-12-07T00:35:52Z","repo_size":null,"slug_size":null,"stack":{"id":"69bee368-352b-4bd0-9b7c-819d860a2588","name":"heroku-18"},"updated_at":"2020-12-07T00:35:52Z","web_url":"https://sheatest21123.herokuapp.com/"}
2020-12-07T00:35:51.998Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:51 [INFO] App ID: sheatest21123
2020-12-07T00:35:51.998Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:51 [INFO] Checking for duplicate config vars
2020-12-07T00:35:51.998Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:51 [INFO] List of Duplicate config vars []
2020-12-07T00:35:51.998Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:51 [INFO] Updating config vars: *map[string]*string{}
2020-12-07T00:35:51.999Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:51 PATCH /apps/sheatest21123/config-vars HTTP/1.1
2020-12-07T00:35:51.999Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Host: api.heroku.com
2020-12-07T00:35:51.999Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: User-Agent: heroku/v5 (linux; amd64) terraform-provider-heroku/dev
2020-12-07T00:35:51.999Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Content-Length: 2
2020-12-07T00:35:51.999Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Accept: application/vnd.heroku+json; version=3
2020-12-07T00:35:52.000Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Authorization: Basic ***REDACTED***
2020-12-07T00:35:52.000Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Content-Type: application/json
2020-12-07T00:35:52.001Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Request-Id: 5bc6f73d-940f-4900-8a03-6b749f539a3f
2020-12-07T00:35:52.001Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Accept-Encoding: gzip
2020-12-07T00:35:52.001Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0:
2020-12-07T00:35:52.001Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: {}
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:52 HTTP/2.0 200 OK
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Cache-Control: private, no-cache
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Content-Type: application/json
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Date: Mon, 07 Dec 2020 00:35:53 GMT
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Etag: "96ba4208836d0c0351ea648c7d05c3e6"
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Last-Modified: Mon, 07 Dec 2020 00:35:52 GMT
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Oauth-Scope: global
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Oauth-Scope-Accepted: global write-protected
2020-12-07T00:35:52.432Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Ratelimit-Multiplier: 1
2020-12-07T00:35:52.433Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Ratelimit-Remaining: 99
2020-12-07T00:35:52.433Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Request-Id: 40d8aa95-f3fe-4221-9fb1-7b6d3ebf0a74,5bc6f73d-940f-4900-8a03-6b749f539a3f,c792245c-c7df-dda7-056d-4c9332e3a60e,5fd57ac0-1650-3eeb-e1b7-9a8fd144e22b
2020-12-07T00:35:52.433Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Vary: Authorization,Accept-Encoding
2020-12-07T00:35:52.434Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Via: 1.1 spaces-router (c9d446e9ac4c), 2.0 spaces-router (c9d446e9ac4c)
2020-12-07T00:35:52.434Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: X-Content-Type-Options: nosniff
2020-12-07T00:35:52.434Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: X-Runtime: 0.047719114
2020-12-07T00:35:52.434Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0:
2020-12-07T00:35:52.434Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: {}
2020-12-07T00:35:52.434Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 2020/12/07 00:35:52 GET /apps/sheatest21123/releases HTTP/1.1
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Host: api.heroku.com
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: User-Agent: heroku/v5 (linux; amd64) terraform-provider-heroku/dev
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Accept: application/vnd.heroku+json; version=3
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Authorization: Basic ***REDACTED***
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Range: version ..; max=1,order=desc
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: Request-Id: 576ad267-657e-4cce-86ca-7b3fed9045e6
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0:
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: panic: runtime error: invalid memory address or nil pointer dereference
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: [signal SIGSEGV: segmentation violation code=0x1 addr=0x0 pc=0xd7911d]
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0:
2020-12-07T00:35:52.435Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: goroutine 51 [running]:
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/heroku-go/v5.RoundTripWithRetryBackoff.RoundTrip.func1(0xc000676a80, 0xc0005c0200)
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/heroku/heroku-go/v5/round_trip_with_retry_backoff.go:35 +0x16d
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/cenkalti/backoff.RetryNotify(0xc00065cd68, 0x1295820, 0xc000676a80, 0x108c4f8, 0x0, 0x0)
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/cenkalti/backoff/retry.go:37 +0xb8
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/heroku-go/v5.RoundTripWithRetryBackoff.RoundTrip(0x0, 0x0, 0x0, 0x0, 0x0, 0xc00018ee00, 0x52e100, 0xc00007e050, 0x2)
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/heroku/heroku-go/v5/round_trip_with_retry_backoff.go:60 +0x207
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/heroku-go/v5.(*Transport).RoundTrip(0xc00045e000, 0xc00018ee00, 0xc00045e000, 0x0, 0x0)
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/heroku/heroku-go/v5/transport.go:96 +0x3dc
2020-12-07T00:35:52.436Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: net/http.send(0xc00018ed00, 0x1289d60, 0xc00045e000, 0x0, 0x0, 0x0, 0xc00000e028, 0x0, 0x1, 0x0)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/opt/hostedtoolcache/go/1.14.12/x64/src/net/http/client.go:252 +0x43e
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: net/http.(*Client).send(0xc0004238c0, 0xc00018ed00, 0x0, 0x0, 0x0, 0xc00000e028, 0x0, 0x1, 0xe7a200)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/opt/hostedtoolcache/go/1.14.12/x64/src/net/http/client.go:176 +0xfa
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: net/http.(*Client).do(0xc0004238c0, 0xc00018ed00, 0x0, 0x0, 0x0)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/opt/hostedtoolcache/go/1.14.12/x64/src/net/http/client.go:699 +0x44a
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: net/http.(*Client).Do(...)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/opt/hostedtoolcache/go/1.14.12/x64/src/net/http/client.go:567
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/heroku-go/v5.(*Service).Do(0xc00047d2c0, 0x12a40e0, 0xc000036078, 0xe3dac0, 0xc0005c00c0, 0x1041f80, 0x3, 0xc0005089e0, 0x1c, 0x0, ...)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/heroku/heroku-go/v5/heroku.go:114 +0x11f
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/heroku-go/v5.(*Service).Get(...)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/heroku/heroku-go/v5/heroku.go:131
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/heroku-go/v5.(*Service).ReleaseList(0xc00047d2c0, 0x12a40e0, 0xc000036078, 0xc0007067e0, 0xd, 0xc00065d580, 0xc0005621e0, 0x0, 0x0, 0xc000208d80, ...)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/heroku/heroku-go/v5/heroku.go:3490 +0x150
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/terraform-provider-heroku/v3/heroku.updateConfigVars(0xc0007067e0, 0xd, 0xc00047d2c0, 0x0, 0xc00070a960, 0xc00065d600, 0x52e100)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/heroku/resource_heroku_app.go:705 +0x453
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/terraform-provider-heroku/v3/heroku.performAppPostCreateTasks(0xc000362770, 0xc00047d2c0, 0xc00065d748, 0x1)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/heroku/resource_heroku_app.go:800 +0x164
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/terraform-provider-heroku/v3/heroku.resourceHerokuAppCreate(0xc000362770, 0xf330c0, 0xc00041f140, 0x0, 0xfb0d60)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/heroku/resource_heroku_app.go:252 +0x2a7
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/heroku/terraform-provider-heroku/v3/heroku.switchHerokuAppCreate(0xc000362770, 0xf330c0, 0xc00041f140, 0x2, 0x1a0dc00)
2020-12-07T00:35:52.437Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/heroku/resource_heroku_app.go:212 +0xe1
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/hashicorp/terraform-plugin-sdk/helper/schema.(*Resource).Apply(0xc000411440, 0xc000506870, 0xc0006609c0, 0xf330c0, 0xc00041f140, 0xeddc01, 0xc0003ad4a8, 0xc000640330)
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/hashicorp/terraform-plugin-sdk/helper/schema/resource.go:310 +0x365
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/hashicorp/terraform-plugin-sdk/helper/schema.(*Provider).Apply(0xc000158980, 0xc00065da10, 0xc000506870, 0xc0006609c0, 0xc00063c1a8, 0xc000654070, 0xedf840)
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/hashicorp/terraform-plugin-sdk/helper/schema/provider.go:294 +0x99
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/hashicorp/terraform-plugin-sdk/internal/helper/plugin.(*GRPCProviderServer).ApplyResourceChange(0xc00000ed50, 0x12a4160, 0xc000632c90, 0xc000362070, 0xc00000ed50, 0xc000632c90, 0xc000302b78)
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/hashicorp/terraform-plugin-sdk/internal/helper/plugin/grpc_provider.go:885 +0x8b4
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: github.com/hashicorp/terraform-plugin-sdk/internal/tfplugin5._Provider_ApplyResourceChange_Handler(0x10009a0, 0xc00000ed50, 0x12a4160, 0xc000632c90, 0xc0000a0540, 0x0, 0x12a4160, 0xc000632c90, 0xc0006381e0, 0x1cf)
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/github.com/hashicorp/terraform-plugin-sdk/internal/tfplugin5/tfplugin5.pb.go:3305 +0x217
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: google.golang.org/grpc.(*Server).processUnaryRPC(0xc000001980, 0x12ae100, 0xc000082f00, 0xc00062e200, 0xc00016e900, 0x19d3520, 0x0, 0x0, 0x0)
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/google.golang.org/grpc/server.go:1024 +0x501
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: google.golang.org/grpc.(*Server).handleStream(0xc000001980, 0x12ae100, 0xc000082f00, 0xc00062e200, 0x0)
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/google.golang.org/grpc/server.go:1313 +0xd3d
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: google.golang.org/grpc.(*Server).serveStreams.func1.1(0xc0000363d0, 0xc000001980, 0x12ae100, 0xc000082f00, 0xc00062e200)
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/google.golang.org/grpc/server.go:722 +0xa1
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: created by google.golang.org/grpc.(*Server).serveStreams.func1
2020-12-07T00:35:52.438Z [DEBUG] plugin.terraform-provider-heroku_v3.1.0: 	/home/runner/work/terraform-provider-heroku/terraform-provider-heroku/vendor/google.golang.org/grpc/server.go:720 +0xa1
2020-12-07T00:35:52.439Z [DEBUG] plugin: plugin process exited: path=/home/host/git/tftest/.terraform/plugins/linux_amd64/terraform-provider-heroku_v3.1.0 pid=1471 error="exit status 2"
2020/12/07 00:35:52 [DEBUG] heroku_app.myapp: apply errored, but we're indicating that via the Error pointer rather than returning it: rpc error: code = Unavailable desc = transport is closing
2020-12-07T00:35:52.440Z [WARN]  plugin.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalMaybeTainted
2020/12/07 00:35:52 [TRACE] EvalMaybeTainted: heroku_app.myapp encountered an error during creation, so it is now marked as tainted
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalWriteState
2020/12/07 00:35:52 [TRACE] EvalWriteState: removing state object for heroku_app.myapp
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalApplyProvisioners
2020/12/07 00:35:52 [TRACE] EvalApplyProvisioners: heroku_app.myapp has no state, so skipping provisioners
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalMaybeTainted
2020/12/07 00:35:52 [TRACE] EvalMaybeTainted: heroku_app.myapp encountered an error during creation, so it is now marked as tainted
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalWriteState
2020/12/07 00:35:52 [TRACE] EvalWriteState: removing state object for heroku_app.myapp
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalIf
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalIf
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalWriteDiff
2020/12/07 00:35:52 [TRACE] <root>: eval: *terraform.EvalApplyPost
2020/12/07 00:35:52 [ERROR] <root>: eval: *terraform.EvalApplyPost, err: rpc error: code = Unavailable desc = transport is closing
2020/12/07 00:35:52 [ERROR] <root>: eval: *terraform.EvalSequence, err: rpc error: code = Unavailable desc = transport is closing
2020/12/07 00:35:52 [TRACE] [walkApply] Exiting eval tree: heroku_app.myapp
2020/12/07 00:35:52 [TRACE] vertex "heroku_app.myapp": visit complete
2020/12/07 00:35:52 [TRACE] dag/walk: upstream of "meta.count-boundary (EachMode fixup)" errored, so skipping
2020/12/07 00:35:52 [TRACE] dag/walk: upstream of "provider.heroku (close)" errored, so skipping
2020/12/07 00:35:52 [TRACE] dag/walk: upstream of "root" errored, so skipping
2020/12/07 00:35:52 [TRACE] statemgr.Filesystem: not making a backup, because the new snapshot is identical to the old
2020/12/07 00:35:52 [TRACE] statemgr.Filesystem: no state changes since last snapshot
2020/12/07 00:35:52 [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2020/12/07 00:35:52 [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2020/12/07 00:35:52 [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
2020-12-07T00:35:52.447Z [DEBUG] plugin: plugin exited