adarobin/crash.log

## crash.log
2019/08/09 09:40:04 [INFO] Terraform version: 0.12.6
2019/08/09 09:40:04 [INFO] Go runtime version: go1.12.7
2019/08/09 09:40:04 [INFO] CLI args: []string{"/usr/local/bin/terraform", "apply"}
2019/08/09 09:40:04 [DEBUG] Attempting to open CLI config file: /Users/adarobin/.terraformrc
2019/08/09 09:40:04 Loading CLI configuration from /Users/adarobin/.terraformrc
2019/08/09 09:40:04 [INFO] CLI command args: []string{"apply"}
2019/08/09 09:40:04 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2019/08/09 09:40:04 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2019/08/09 09:40:04 [DEBUG] New state was assigned lineage "34b35dc9-7f96-330d-ddce-e54036caabc2"
2019/08/09 09:40:04 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2019/08/09 09:40:04 [TRACE] Meta.Backend: instantiated backend of type <nil>
2019/08/09 09:40:04 [DEBUG] checking for provider in "."
2019/08/09 09:40:04 [DEBUG] checking for provider in "/usr/local/bin"
2019/08/09 09:40:04 [DEBUG] checking for provider in ".terraform/plugins/darwin_amd64"
2019/08/09 09:40:04 [DEBUG] found provider "terraform-provider-null_v2.1.2_x4"
2019/08/09 09:40:04 [DEBUG] checking for provider in "/Users/adarobin/.terraform.d/plugins"
2019/08/09 09:40:04 [DEBUG] found valid plugin: "null", "2.1.2", "/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4"
2019/08/09 09:40:04 [DEBUG] checking for provisioner in "."
2019/08/09 09:40:04 [DEBUG] checking for provisioner in "/usr/local/bin"
2019/08/09 09:40:04 [DEBUG] checking for provisioner in ".terraform/plugins/darwin_amd64"
2019/08/09 09:40:04 [DEBUG] checking for provisioner in "/Users/adarobin/.terraform.d/plugins"
2019/08/09 09:40:04 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2019/08/09 09:40:04 [INFO] backend/local: starting Apply operation
2019/08/09 09:40:04 [TRACE] backend/local: requesting state manager for workspace "default"
2019/08/09 09:40:04 [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
2019/08/09 09:40:04 [TRACE] backend/local: requesting state lock for workspace "default"
2019/08/09 09:40:04 [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2019/08/09 09:40:04 [TRACE] statemgr.Filesystem: existing snapshot has lineage "ecdc9fed-cc10-1efa-e51d-f4a2f0310ecc" serial 23
2019/08/09 09:40:04 [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2019/08/09 09:40:04 [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2019/08/09 09:40:04 [TRACE] backend/local: reading remote state for workspace "default"
2019/08/09 09:40:04 [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2019/08/09 09:40:04 [TRACE] statemgr.Filesystem: read snapshot with lineage "ecdc9fed-cc10-1efa-e51d-f4a2f0310ecc" serial 23
2019/08/09 09:40:04 [TRACE] backend/local: retrieving local state snapshot for workspace "default"
2019/08/09 09:40:04 [TRACE] backend/local: building context for current working directory
2019/08/09 09:40:04 [TRACE] terraform.NewContext: starting
2019/08/09 09:40:04 [TRACE] terraform.NewContext: resolving provider version selections
2019/08/09 09:40:04 [TRACE] terraform.NewContext: loading provider schemas
2019/08/09 09:40:04 [TRACE] LoadSchemas: retrieving schema for provider type "null"
2019-08-09T09:40:04.775-0400 [INFO]  plugin: configuring client automatic mTLS
2019-08-09T09:40:04.803-0400 [DEBUG] plugin: starting plugin: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 args=[/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4]
2019-08-09T09:40:04.809-0400 [DEBUG] plugin: plugin started: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84765
2019-08-09T09:40:04.809-0400 [DEBUG] plugin: waiting for RPC address: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4
2019-08-09T09:40:04.826-0400 [INFO]  plugin.terraform-provider-null_v2.1.2_x4: configuring server automatic mTLS: timestamp=2019-08-09T09:40:04.826-0400
2019-08-09T09:40:04.859-0400 [DEBUG] plugin.terraform-provider-null_v2.1.2_x4: plugin address: address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin312562336 network=unix timestamp=2019-08-09T09:40:04.859-0400
2019-08-09T09:40:04.859-0400 [DEBUG] plugin: using plugin: version=5
2019/08/09 09:40:04 [TRACE] GRPCProvider: GetSchema
2019/08/09 09:40:04 [TRACE] GRPCProvider: Close
2019-08-09T09:40:04.935-0400 [DEBUG] plugin: plugin process exited: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84765
2019-08-09T09:40:04.935-0400 [DEBUG] plugin: plugin exited
2019/08/09 09:40:04 [TRACE] LoadSchemas: retrieving schema for provisioner "local-exec"
2019-08-09T09:40:04.935-0400 [DEBUG] plugin: starting plugin: path=/usr/local/bin/terraform args=[/usr/local/bin/terraform, internal-plugin, provisioner, local-exec]
2019-08-09T09:40:04.939-0400 [DEBUG] plugin: plugin started: path=/usr/local/bin/terraform pid=84766
2019-08-09T09:40:04.939-0400 [DEBUG] plugin: waiting for RPC address: path=/usr/local/bin/terraform
2019-08-09T09:40:04.965-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:04 [INFO] Terraform version: 0.12.6
2019-08-09T09:40:04.965-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:04 [INFO] Go runtime version: go1.12.7
2019-08-09T09:40:04.965-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:04 [INFO] CLI args: []string{"/usr/local/bin/terraform", "internal-plugin", "provisioner", "local-exec"}
2019-08-09T09:40:04.965-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:04 [DEBUG] Attempting to open CLI config file: /Users/adarobin/.terraformrc
2019-08-09T09:40:04.965-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:04 Loading CLI configuration from /Users/adarobin/.terraformrc
2019-08-09T09:40:04.967-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:04 [INFO] CLI command args: []string{"internal-plugin", "provisioner", "local-exec"}
2019-08-09T09:40:04.967-0400 [DEBUG] plugin.terraform: local-exec-provisioner (internal) 2019/08/09 09:40:04 [INFO] Starting provisioner plugin local-exec
2019-08-09T09:40:04.969-0400 [DEBUG] plugin: using plugin: version=5
2019-08-09T09:40:04.969-0400 [DEBUG] plugin.terraform: plugin address: address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin132060165 network=unix timestamp=2019-08-09T09:40:04.969-0400
2019-08-09T09:40:04.982-0400 [DEBUG] plugin: plugin process exited: path=/usr/local/bin/terraform pid=84766
2019-08-09T09:40:04.982-0400 [DEBUG] plugin: plugin exited
2019/08/09 09:40:04 [TRACE] terraform.NewContext: complete
2019/08/09 09:40:04 [TRACE] backend/local: finished building terraform.Context
2019/08/09 09:40:04 [TRACE] backend/local: requesting interactive input, if necessary
2019/08/09 09:40:04 [TRACE] Context.Input: Prompting for variables
2019/08/09 09:40:04 [TRACE] Context.Input: Prompting for provider arguments
2019/08/09 09:40:04 [TRACE] Context.Input: Provider provider.null implied by resource block at main.tf:16,1-32
2019/08/09 09:40:04 [TRACE] Context.Input: Input for provider.null: map[string]cty.Value{}
2019/08/09 09:40:04 [TRACE] backend/local: running validation operation
2019/08/09 09:40:04 [INFO] terraform: building graph: GraphTypeValidate
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.ConfigTransformer
2019/08/09 09:40:04 [TRACE] ConfigTransformer: Starting for path:
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
null_resource.test - *terraform.NodeValidatableResource
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.LocalTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.OutputTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.StateTransformer
2019/08/09 09:40:04 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2019/08/09 09:40:04 [TRACE] AttachResourceConfigTransformer: attaching to "null_resource.test" (*terraform.NodeValidatableResource) config from main.tf:16,1-32
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.RootVariableTransformer with new graph:
null_resource.test - *terraform.NodeValidatableResource
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2019/08/09 09:40:04 [TRACE] MissingProviderTransformer: added implicit provisioner .local-exec, first implied by null_resource.test
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer with new graph:
null_resource.test - *terraform.NodeValidatableResource
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2019/08/09 09:40:04 [TRACE] ProvisionerTransformer: null_resource.test is provisioned by .local-exec ("provisioner.local-exec")
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.ProvisionerTransformer with new graph:
null_resource.test - *terraform.NodeValidatableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
null_resource.test - *terraform.NodeValidatableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2019/08/09 09:40:04 [DEBUG] adding implicit provider configuration provider.null, implied first by null_resource.test
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
null_resource.test - *terraform.NodeValidatableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2019/08/09 09:40:04 [TRACE] ProviderTransformer: null_resource.test is provided by provider.null or inherited equivalent
2019/08/09 09:40:04 [TRACE] ProviderTransformer: exact match for provider.null serving null_resource.test
2019/08/09 09:40:04 [DEBUG] ProviderTransformer: "null_resource.test" (*terraform.NodeValidatableResource) needs provider.null
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2019/08/09 09:40:04 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2019/08/09 09:40:04 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.null
2019/08/09 09:40:04 [TRACE] AttachSchemaTransformer: attaching resource schema to null_resource.test
2019/08/09 09:40:04 [TRACE] AttachSchemaTransformer: attaching provisioner "local-exec" config schema to null_resource.test
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/08/09 09:40:04 [DEBUG] ReferenceTransformer: "null_resource.test" references: [var.test]
2019/08/09 09:40:04 [DEBUG] ReferenceTransformer: "var.test" references: []
2019/08/09 09:40:04 [DEBUG] ReferenceTransformer: "provisioner.local-exec" references: []
2019/08/09 09:40:04 [DEBUG] ReferenceTransformer: "provider.null" references: []
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.CloseProvisionerTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.CloseProvisionerTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test - *terraform.NodeValidatableResource
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.RootTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test - *terraform.NodeValidatableResource
root - terraform.graphNodeRoot
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  provider.null (close) - *terraform.graphNodeCloseProvider
  provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2019/08/09 09:40:04 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodeValidatableResource
null_resource.test - *terraform.NodeValidatableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodeValidatableResource
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test - *terraform.NodeValidatableResource
root - terraform.graphNodeRoot
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  provider.null (close) - *terraform.graphNodeCloseProvider
  provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:04 [DEBUG] Starting graph walk: walkValidate
2019/08/09 09:40:04 [TRACE] dag/walk: updating graph
2019/08/09 09:40:04 [TRACE] dag/walk: added new vertex: "provisioner.local-exec (close)"
2019/08/09 09:40:04 [TRACE] dag/walk: added new vertex: "root"
2019/08/09 09:40:04 [TRACE] dag/walk: added new vertex: "null_resource.test"
2019/08/09 09:40:04 [TRACE] dag/walk: added new vertex: "var.test"
2019/08/09 09:40:04 [TRACE] dag/walk: added new vertex: "provisioner.local-exec"
2019/08/09 09:40:04 [TRACE] dag/walk: added new vertex: "provider.null"
2019/08/09 09:40:04 [TRACE] dag/walk: added new vertex: "meta.count-boundary (EachMode fixup)"
2019/08/09 09:40:04 [TRACE] dag/walk: added new vertex: "provider.null (close)"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "root" waiting on "provisioner.local-exec (close)"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "null_resource.test" waiting on "provider.null"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "provisioner.local-exec (close)" waiting on "null_resource.test"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "root" waiting on "meta.count-boundary (EachMode fixup)"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "root" waiting on "provider.null (close)"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "null_resource.test" waiting on "provisioner.local-exec"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "null_resource.test" waiting on "var.test"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "provider.null (close)" waiting on "null_resource.test"
2019/08/09 09:40:04 [TRACE] dag/walk: added edge: "meta.count-boundary (EachMode fixup)" waiting on "null_resource.test"
2019/08/09 09:40:04 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2019/08/09 09:40:04 [TRACE] dag/walk: dependencies changed for "null_resource.test", sending new deps
2019/08/09 09:40:04 [TRACE] dag/walk: dependencies changed for "provisioner.local-exec (close)", sending new deps
2019/08/09 09:40:04 [TRACE] dag/walk: dependencies changed for "provider.null (close)", sending new deps
2019/08/09 09:40:04 [TRACE] dag/walk: dependencies changed for "meta.count-boundary (EachMode fixup)", sending new deps
2019/08/09 09:40:04 [TRACE] dag/walk: visiting "var.test"
2019/08/09 09:40:04 [TRACE] vertex "var.test": starting visit (*terraform.NodeRootVariable)
2019/08/09 09:40:04 [TRACE] dag/walk: visiting "provider.null"
2019/08/09 09:40:04 [TRACE] vertex "provider.null": starting visit (*terraform.NodeApplyableProvider)
2019/08/09 09:40:04 [TRACE] dag/walk: visiting "provisioner.local-exec"
2019/08/09 09:40:04 [TRACE] vertex "provisioner.local-exec": starting visit (*terraform.NodeProvisioner)
2019/08/09 09:40:04 [TRACE] vertex "var.test": visit complete
2019/08/09 09:40:04 [TRACE] vertex "provisioner.local-exec": evaluating
2019/08/09 09:40:04 [TRACE] [walkValidate] Entering eval tree: provisioner.local-exec
2019/08/09 09:40:04 [TRACE] vertex "provider.null": evaluating
2019/08/09 09:40:04 [TRACE] [walkValidate] Entering eval tree: provider.null
2019/08/09 09:40:04 [TRACE] <root>: eval: *terraform.EvalInitProvisioner
2019/08/09 09:40:04 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:04 [TRACE] <root>: eval: *terraform.EvalInitProvider
2019-08-09T09:40:04.985-0400 [INFO]  plugin: configuring client automatic mTLS
2019-08-09T09:40:04.985-0400 [DEBUG] plugin: starting plugin: path=/usr/local/bin/terraform args=[/usr/local/bin/terraform, internal-plugin, provisioner, local-exec]
2019-08-09T09:40:04.987-0400 [DEBUG] plugin: plugin started: path=/usr/local/bin/terraform pid=84767
2019-08-09T09:40:04.987-0400 [DEBUG] plugin: waiting for RPC address: path=/usr/local/bin/terraform
2019-08-09T09:40:05.011-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [INFO] Terraform version: 0.12.6
2019-08-09T09:40:05.011-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [INFO] Go runtime version: go1.12.7
2019-08-09T09:40:05.011-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [INFO] CLI args: []string{"/usr/local/bin/terraform", "internal-plugin", "provisioner", "local-exec"}
2019-08-09T09:40:05.011-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [DEBUG] Attempting to open CLI config file: /Users/adarobin/.terraformrc
2019-08-09T09:40:05.011-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 Loading CLI configuration from /Users/adarobin/.terraformrc
2019-08-09T09:40:05.013-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [INFO] CLI command args: []string{"internal-plugin", "provisioner", "local-exec"}
2019-08-09T09:40:05.013-0400 [DEBUG] plugin.terraform: local-exec-provisioner (internal) 2019/08/09 09:40:05 [INFO] Starting provisioner plugin local-exec
2019-08-09T09:40:05.015-0400 [DEBUG] plugin: using plugin: version=5
2019-08-09T09:40:05.016-0400 [DEBUG] plugin.terraform: plugin address: address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin056083538 network=unix timestamp=2019-08-09T09:40:05.015-0400
2019/08/09 09:40:05 [TRACE] [walkValidate] Exiting eval tree: provisioner.local-exec
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec": visit complete
2019-08-09T09:40:05.027-0400 [DEBUG] plugin: starting plugin: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 args=[/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4]
2019-08-09T09:40:05.036-0400 [DEBUG] plugin: plugin started: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84768
2019-08-09T09:40:05.036-0400 [DEBUG] plugin: waiting for RPC address: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4
2019-08-09T09:40:05.051-0400 [INFO]  plugin.terraform-provider-null_v2.1.2_x4: configuring server automatic mTLS: timestamp=2019-08-09T09:40:05.050-0400
2019-08-09T09:40:05.094-0400 [DEBUG] plugin: using plugin: version=5
2019-08-09T09:40:05.094-0400 [DEBUG] plugin.terraform-provider-null_v2.1.2_x4: plugin address: address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin374018103 network=unix timestamp=2019-08-09T09:40:05.094-0400
2019/08/09 09:40:05 [TRACE] BuiltinEvalContext: Initialized "null" provider for provider.null
2019/08/09 09:40:05 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalValidateProvider
2019/08/09 09:40:05 [TRACE] buildProviderConfig for provider.null: no configuration at all
2019/08/09 09:40:05 [TRACE] GRPCProvider: GetSchema
2019/08/09 09:40:05 [TRACE] GRPCProvider: PrepareProviderConfig
2019/08/09 09:40:05 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:05 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:05 [TRACE] [walkValidate] Exiting eval tree: provider.null
2019/08/09 09:40:05 [TRACE] vertex "provider.null": visit complete
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "null_resource.test"
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": starting visit (*terraform.NodeValidatableResource)
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": evaluating
2019/08/09 09:40:05 [TRACE] [walkValidate] Entering eval tree: null_resource.test
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalValidateResource
2019/08/09 09:40:05 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalGetProvisioner
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalValidateProvisioner
2019/08/09 09:40:05 [TRACE] [walkValidate] Exiting eval tree: null_resource.test
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": visit complete
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "provisioner.local-exec (close)"
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec (close)": starting visit (*terraform.graphNodeCloseProvisioner)
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "meta.count-boundary (EachMode fixup)"
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec (close)": evaluating
2019/08/09 09:40:05 [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2019/08/09 09:40:05 [TRACE] [walkValidate] Entering eval tree: provisioner.local-exec (close)
2019/08/09 09:40:05 [TRACE] vertex "meta.count-boundary (EachMode fixup)": evaluating
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "provider.null (close)"
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalCloseProvisioner
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": starting visit (*terraform.graphNodeCloseProvider)
2019/08/09 09:40:05 [TRACE] [walkValidate] Entering eval tree: meta.count-boundary (EachMode fixup)
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": evaluating
2019/08/09 09:40:05 [TRACE] [walkValidate] Entering eval tree: provider.null (close)
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalCountFixZeroOneBoundaryGlobal
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalCloseProvider
2019/08/09 09:40:05 [TRACE] GRPCProvider: Close
2019/08/09 09:40:05 [TRACE] [walkValidate] Exiting eval tree: meta.count-boundary (EachMode fixup)
2019/08/09 09:40:05 [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2019-08-09T09:40:05.180-0400 [DEBUG] plugin: plugin process exited: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84768
2019-08-09T09:40:05.180-0400 [DEBUG] plugin: plugin exited
2019/08/09 09:40:05 [TRACE] [walkValidate] Exiting eval tree: provider.null (close)
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": visit complete
2019-08-09T09:40:05.182-0400 [DEBUG] plugin: plugin process exited: path=/usr/local/bin/terraform pid=84767
2019-08-09T09:40:05.182-0400 [DEBUG] plugin: plugin exited
2019/08/09 09:40:05 [TRACE] [walkValidate] Exiting eval tree: provisioner.local-exec (close)
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec (close)": visit complete
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "root"
2019/08/09 09:40:05 [TRACE] vertex "root": starting visit (terraform.graphNodeRoot)
2019/08/09 09:40:05 [TRACE] vertex "root": visit complete
2019/08/09 09:40:05 [INFO] backend/local: apply calling Refresh
2019/08/09 09:40:05 [INFO] terraform: building graph: GraphTypeRefresh
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ConfigTransformer
2019/08/09 09:40:05 [TRACE] ConfigTransformer: Starting for path:
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ConfigTransformer
2019/08/09 09:40:05 [TRACE] ConfigTransformer: Starting for path:
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ConfigTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.StateTransformer
2019/08/09 09:40:05 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2019/08/09 09:40:05 [TRACE] AttachResourceConfigTransformer: attaching to "null_resource.test" (*terraform.NodeRefreshableManagedResource) config from main.tf:16,1-32
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.RootVariableTransformer with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.LocalTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.OutputTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2019/08/09 09:40:05 [DEBUG] adding implicit provider configuration provider.null, implied first by null_resource.test
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
provider.null - *terraform.NodeApplyableProvider
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2019/08/09 09:40:05 [TRACE] ProviderTransformer: null_resource.test is provided by provider.null or inherited equivalent
2019/08/09 09:40:05 [TRACE] ProviderTransformer: exact match for provider.null serving null_resource.test
2019/08/09 09:40:05 [DEBUG] ProviderTransformer: "null_resource.test" (*terraform.NodeRefreshableManagedResource) needs provider.null
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
  provider.null - *terraform.NodeApplyableProvider
provider.null - *terraform.NodeApplyableProvider
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
  provider.null - *terraform.NodeApplyableProvider
provider.null - *terraform.NodeApplyableProvider
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2019/08/09 09:40:05 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.null
2019/08/09 09:40:05 [TRACE] AttachSchemaTransformer: attaching resource schema to null_resource.test
2019/08/09 09:40:05 [TRACE] AttachSchemaTransformer: attaching provisioner "local-exec" config schema to null_resource.test
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "null_resource.test" references: [var.test]
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "var.test" references: []
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "provider.null" references: []
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
  provider.null - *terraform.NodeApplyableProvider
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
  provider.null - *terraform.NodeApplyableProvider
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodeRefreshableManagedResource
  provider.null - *terraform.NodeApplyableProvider
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.RootTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
null_resource.test - *terraform.NodeRefreshableManagedResource
  provider.null - *terraform.NodeApplyableProvider
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodeRefreshableManagedResource
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [DEBUG] Starting graph walk: walkRefresh
2019/08/09 09:40:05 [TRACE] dag/walk: updating graph
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "var.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "provider.null"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "provider.null (close)"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "null_resource.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "null_resource.test" waiting on "provider.null"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "null_resource.test" waiting on "var.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "provider.null (close)" waiting on "null_resource.test"
2019/08/09 09:40:05 [TRACE] dag/walk: dependencies changed for "provider.null (close)", sending new deps
2019/08/09 09:40:05 [TRACE] dag/walk: dependencies changed for "null_resource.test", sending new deps
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "var.test"
2019/08/09 09:40:05 [TRACE] vertex "var.test": starting visit (*terraform.NodeRootVariable)
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "provider.null"
2019/08/09 09:40:05 [TRACE] vertex "provider.null": starting visit (*terraform.NodeApplyableProvider)
2019/08/09 09:40:05 [TRACE] vertex "var.test": visit complete
2019/08/09 09:40:05 [TRACE] vertex "provider.null": evaluating
2019/08/09 09:40:05 [TRACE] [walkRefresh] Entering eval tree: provider.null
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalInitProvider
2019-08-09T09:40:05.183-0400 [INFO]  plugin: configuring client automatic mTLS
2019-08-09T09:40:05.219-0400 [DEBUG] plugin: starting plugin: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 args=[/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4]
2019-08-09T09:40:05.229-0400 [DEBUG] plugin: plugin started: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84769
2019-08-09T09:40:05.230-0400 [DEBUG] plugin: waiting for RPC address: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4
2019-08-09T09:40:05.241-0400 [INFO]  plugin.terraform-provider-null_v2.1.2_x4: configuring server automatic mTLS: timestamp=2019-08-09T09:40:05.240-0400
2019-08-09T09:40:05.285-0400 [DEBUG] plugin: using plugin: version=5
2019-08-09T09:40:05.285-0400 [DEBUG] plugin.terraform-provider-null_v2.1.2_x4: plugin address: address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin412747724 network=unix timestamp=2019-08-09T09:40:05.284-0400
2019/08/09 09:40:05 [TRACE] BuiltinEvalContext: Initialized "null" provider for provider.null
2019/08/09 09:40:05 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:05 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalConfigProvider
2019/08/09 09:40:05 [TRACE] buildProviderConfig for provider.null: no configuration at all
2019/08/09 09:40:05 [TRACE] GRPCProvider: GetSchema
2019/08/09 09:40:05 [TRACE] GRPCProvider: Configure
2019/08/09 09:40:05 [TRACE] [walkRefresh] Exiting eval tree: provider.null
2019/08/09 09:40:05 [TRACE] vertex "provider.null": visit complete
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "null_resource.test"
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": starting visit (*terraform.NodeRefreshableManagedResource)
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": expanding dynamic subgraph
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
null_resource.test["foo"] - *terraform.NodeRefreshableManagedResourceInstance
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.OrphanResourceCountTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.OrphanResourceCountTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/08/09 09:40:05 [WARN] no schema for provisioner "local-exec" is attached to null_resource.test, so provisioner block references cannot be detected
2019/08/09 09:40:05 [WARN] no schema for provisioner "local-exec" is attached to null_resource.test, so provisioner block references cannot be detected
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "null_resource.test[\"foo\"]" references: []
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.RootTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": entering dynamic subgraph
2019/08/09 09:40:05 [TRACE] dag/walk: updating graph
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "null_resource.test[\"foo\"]"
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "null_resource.test[\"foo\"]"
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test[\"foo\"]": starting visit (*terraform.NodeRefreshableManagedResourceInstance)
2019/08/09 09:40:05 [TRACE] NodeRefreshableManagedResourceInstance: null_resource.test["foo"] will be refreshed
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test[\"foo\"]": evaluating
2019/08/09 09:40:05 [TRACE] [walkRefresh] Entering eval tree: null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalReadState
2019/08/09 09:40:05 [TRACE] EvalReadState: reading state for null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] UpgradeResourceState: schema version of null_resource.test["foo"] is still 0; calling provider "null" for any other minor fixups
2019/08/09 09:40:05 [TRACE] GRPCProvider: UpgradeResourceState
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalRefresh
2019/08/09 09:40:05 [TRACE] GRPCProvider: ReadResource
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalWriteState
2019/08/09 09:40:05 [TRACE] EvalWriteState: writing current state object for null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] [walkRefresh] Exiting eval tree: null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test[\"foo\"]": visit complete
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": dynamic subgraph completed successfully
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": visit complete
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "provider.null (close)"
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": starting visit (*terraform.graphNodeCloseProvider)
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": evaluating
2019/08/09 09:40:05 [TRACE] [walkRefresh] Entering eval tree: provider.null (close)
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalCloseProvider
2019/08/09 09:40:05 [TRACE] GRPCProvider: Close
2019-08-09T09:40:05.358-0400 [DEBUG] plugin: plugin process exited: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84769
2019-08-09T09:40:05.358-0400 [DEBUG] plugin: plugin exited
2019/08/09 09:40:05 [TRACE] [walkRefresh] Exiting eval tree: provider.null (close)
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": visit complete
2019/08/09 09:40:05 [INFO] backend/local: apply calling Plan
2019/08/09 09:40:05 [INFO] terraform: building graph: GraphTypePlan
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ConfigTransformer
2019/08/09 09:40:05 [TRACE] ConfigTransformer: Starting for path:
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
null_resource.test - *terraform.NodePlannableResource
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.LocalTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.OutputTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.StateTransformer
2019/08/09 09:40:05 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2019/08/09 09:40:05 [TRACE] AttachResourceConfigTransformer: attaching to "null_resource.test" (*terraform.NodePlannableResource) config from main.tf:16,1-32
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.RootVariableTransformer with new graph:
null_resource.test - *terraform.NodePlannableResource
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2019/08/09 09:40:05 [TRACE] MissingProviderTransformer: added implicit provisioner .local-exec, first implied by null_resource.test
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer with new graph:
null_resource.test - *terraform.NodePlannableResource
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2019/08/09 09:40:05 [TRACE] ProvisionerTransformer: null_resource.test is provisioned by .local-exec ("provisioner.local-exec")
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ProvisionerTransformer with new graph:
null_resource.test - *terraform.NodePlannableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
null_resource.test - *terraform.NodePlannableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2019/08/09 09:40:05 [DEBUG] adding implicit provider configuration provider.null, implied first by null_resource.test
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
null_resource.test - *terraform.NodePlannableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2019/08/09 09:40:05 [TRACE] ProviderTransformer: null_resource.test is provided by provider.null or inherited equivalent
2019/08/09 09:40:05 [TRACE] ProviderTransformer: exact match for provider.null serving null_resource.test
2019/08/09 09:40:05 [DEBUG] ProviderTransformer: "null_resource.test" (*terraform.NodePlannableResource) needs provider.null
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2019/08/09 09:40:05 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2019/08/09 09:40:05 [TRACE] AttachSchemaTransformer: attaching resource schema to null_resource.test
2019/08/09 09:40:05 [TRACE] AttachSchemaTransformer: attaching provisioner "local-exec" config schema to null_resource.test
2019/08/09 09:40:05 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.null
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "null_resource.test" references: [var.test]
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "var.test" references: []
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "provisioner.local-exec" references: []
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "provider.null" references: []
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2019/08/09 09:40:05 [TRACE] ForcedCBDTransformer: "null_resource.test" (*terraform.NodePlannableResource) has no CBD descendent, so skipping
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.CloseProvisionerTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.CloseProvisionerTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test - *terraform.NodePlannableResource
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.RootTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test - *terraform.NodePlannableResource
root - terraform.graphNodeRoot
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  provider.null (close) - *terraform.graphNodeCloseProvider
  provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test - *terraform.NodePlannableResource
null_resource.test - *terraform.NodePlannableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test - *terraform.NodePlannableResource
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test - *terraform.NodePlannableResource
root - terraform.graphNodeRoot
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  provider.null (close) - *terraform.graphNodeCloseProvider
  provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:05 [DEBUG] Starting graph walk: walkPlan
2019/08/09 09:40:05 [TRACE] dag/walk: updating graph
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "var.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "provisioner.local-exec"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "provider.null"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "meta.count-boundary (EachMode fixup)"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "provider.null (close)"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "provisioner.local-exec (close)"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "root"
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "null_resource.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "meta.count-boundary (EachMode fixup)" waiting on "null_resource.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "provider.null (close)" waiting on "null_resource.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "null_resource.test" waiting on "var.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "root" waiting on "meta.count-boundary (EachMode fixup)"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "null_resource.test" waiting on "provisioner.local-exec"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "null_resource.test" waiting on "provider.null"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "provisioner.local-exec (close)" waiting on "null_resource.test"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "root" waiting on "provider.null (close)"
2019/08/09 09:40:05 [TRACE] dag/walk: added edge: "root" waiting on "provisioner.local-exec (close)"
2019/08/09 09:40:05 [TRACE] dag/walk: dependencies changed for "provisioner.local-exec (close)", sending new deps
2019/08/09 09:40:05 [TRACE] dag/walk: dependencies changed for "meta.count-boundary (EachMode fixup)", sending new deps
2019/08/09 09:40:05 [TRACE] dag/walk: dependencies changed for "provider.null (close)", sending new deps
2019/08/09 09:40:05 [TRACE] dag/walk: dependencies changed for "null_resource.test", sending new deps
2019/08/09 09:40:05 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "var.test"
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "provisioner.local-exec"
2019/08/09 09:40:05 [TRACE] vertex "var.test": starting visit (*terraform.NodeRootVariable)
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec": starting visit (*terraform.NodeProvisioner)
2019/08/09 09:40:05 [TRACE] vertex "var.test": visit complete
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec": evaluating
2019/08/09 09:40:05 [TRACE] [walkPlan] Entering eval tree: provisioner.local-exec
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "provider.null"
2019/08/09 09:40:05 [TRACE] vertex "provider.null": starting visit (*terraform.NodeApplyableProvider)
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalInitProvisioner
2019/08/09 09:40:05 [TRACE] vertex "provider.null": evaluating
2019/08/09 09:40:05 [TRACE] [walkPlan] Entering eval tree: provider.null
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalInitProvider
2019-08-09T09:40:05.359-0400 [INFO]  plugin: configuring client automatic mTLS
2019-08-09T09:40:05.359-0400 [DEBUG] plugin: starting plugin: path=/usr/local/bin/terraform args=[/usr/local/bin/terraform, internal-plugin, provisioner, local-exec]
2019-08-09T09:40:05.361-0400 [DEBUG] plugin: plugin started: path=/usr/local/bin/terraform pid=84770
2019-08-09T09:40:05.361-0400 [DEBUG] plugin: waiting for RPC address: path=/usr/local/bin/terraform
2019-08-09T09:40:05.378-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [INFO] Terraform version: 0.12.6
2019-08-09T09:40:05.378-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [INFO] Go runtime version: go1.12.7
2019-08-09T09:40:05.378-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [INFO] CLI args: []string{"/usr/local/bin/terraform", "internal-plugin", "provisioner", "local-exec"}
2019-08-09T09:40:05.378-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [DEBUG] Attempting to open CLI config file: /Users/adarobin/.terraformrc
2019-08-09T09:40:05.378-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 Loading CLI configuration from /Users/adarobin/.terraformrc
2019-08-09T09:40:05.379-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:05 [INFO] CLI command args: []string{"internal-plugin", "provisioner", "local-exec"}
2019-08-09T09:40:05.380-0400 [DEBUG] plugin.terraform: local-exec-provisioner (internal) 2019/08/09 09:40:05 [INFO] Starting provisioner plugin local-exec
2019-08-09T09:40:05.381-0400 [DEBUG] plugin.terraform: plugin address: address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin349528881 network=unix timestamp=2019-08-09T09:40:05.381-0400
2019-08-09T09:40:05.381-0400 [DEBUG] plugin: using plugin: version=5
2019/08/09 09:40:05 [TRACE] [walkPlan] Exiting eval tree: provisioner.local-exec
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec": visit complete
2019-08-09T09:40:05.396-0400 [DEBUG] plugin: starting plugin: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 args=[/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4]
2019-08-09T09:40:05.403-0400 [DEBUG] plugin: plugin started: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84771
2019-08-09T09:40:05.403-0400 [DEBUG] plugin: waiting for RPC address: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4
2019-08-09T09:40:05.421-0400 [INFO]  plugin.terraform-provider-null_v2.1.2_x4: configuring server automatic mTLS: timestamp=2019-08-09T09:40:05.421-0400
2019-08-09T09:40:05.459-0400 [DEBUG] plugin: using plugin: version=5
2019-08-09T09:40:05.459-0400 [DEBUG] plugin.terraform-provider-null_v2.1.2_x4: plugin address: address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin657270942 network=unix timestamp=2019-08-09T09:40:05.459-0400
2019/08/09 09:40:05 [TRACE] BuiltinEvalContext: Initialized "null" provider for provider.null
2019/08/09 09:40:05 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:05 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalConfigProvider
2019/08/09 09:40:05 [TRACE] buildProviderConfig for provider.null: no configuration at all
2019/08/09 09:40:05 [TRACE] GRPCProvider: GetSchema
2019/08/09 09:40:05 [TRACE] GRPCProvider: Configure
2019/08/09 09:40:05 [TRACE] [walkPlan] Exiting eval tree: provider.null
2019/08/09 09:40:05 [TRACE] vertex "provider.null": visit complete
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "null_resource.test"
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": starting visit (*terraform.NodePlannableResource)
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": evaluating
2019/08/09 09:40:05 [TRACE] [walkPlan] Entering eval tree: null_resource.test
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalWriteResourceState
2019/08/09 09:40:05 [TRACE] [walkPlan] Exiting eval tree: null_resource.test
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": expanding dynamic subgraph
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
null_resource.test["foo"] - *terraform.NodePlannableResourceInstance
------
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.OrphanResourceCountTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.OrphanResourceCountTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/08/09 09:40:05 [DEBUG] ReferenceTransformer: "null_resource.test[\"foo\"]" references: []
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2019/08/09 09:40:05 [TRACE] Executing graph transform *terraform.RootTransformer
2019/08/09 09:40:05 [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": entering dynamic subgraph
2019/08/09 09:40:05 [TRACE] dag/walk: updating graph
2019/08/09 09:40:05 [TRACE] dag/walk: added new vertex: "null_resource.test[\"foo\"]"
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "null_resource.test[\"foo\"]"
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test[\"foo\"]": starting visit (*terraform.NodePlannableResourceInstance)
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test[\"foo\"]": evaluating
2019/08/09 09:40:05 [TRACE] [walkPlan] Entering eval tree: null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalReadState
2019/08/09 09:40:05 [TRACE] EvalReadState: reading state for null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] UpgradeResourceState: schema version of null_resource.test["foo"] is still 0; calling provider "null" for any other minor fixups
2019/08/09 09:40:05 [TRACE] GRPCProvider: UpgradeResourceState
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalValidateSelfRef
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalDiff
2019/08/09 09:40:05 [TRACE] Re-validating config for "null_resource.test[\"foo\"]"
2019/08/09 09:40:05 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2019/08/09 09:40:05 [TRACE] GRPCProvider: PlanResourceChange
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalCheckPreventDestroy
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalWriteState
2019/08/09 09:40:05 [TRACE] EvalWriteState: writing current state object for null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalWriteDiff
2019/08/09 09:40:05 [TRACE] EvalWriteDiff: recorded DeleteThenCreate change for null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] [walkPlan] Exiting eval tree: null_resource.test["foo"]
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test[\"foo\"]": visit complete
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": dynamic subgraph completed successfully
2019/08/09 09:40:05 [TRACE] vertex "null_resource.test": visit complete
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "provisioner.local-exec (close)"
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec (close)": starting visit (*terraform.graphNodeCloseProvisioner)
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec (close)": evaluating
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "meta.count-boundary (EachMode fixup)"
2019/08/09 09:40:05 [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2019/08/09 09:40:05 [TRACE] vertex "meta.count-boundary (EachMode fixup)": evaluating
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "provider.null (close)"
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": starting visit (*terraform.graphNodeCloseProvider)
2019/08/09 09:40:05 [TRACE] [walkPlan] Entering eval tree: meta.count-boundary (EachMode fixup)
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": evaluating
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalCountFixZeroOneBoundaryGlobal
2019/08/09 09:40:05 [TRACE] [walkPlan] Entering eval tree: provisioner.local-exec (close)
2019/08/09 09:40:05 [TRACE] [walkPlan] Entering eval tree: provider.null (close)
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalCloseProvider
2019/08/09 09:40:05 [TRACE] GRPCProvider: Close
2019/08/09 09:40:05 [TRACE] <root>: eval: *terraform.EvalCloseProvisioner
2019/08/09 09:40:05 [TRACE] [walkPlan] Exiting eval tree: meta.count-boundary (EachMode fixup)
2019/08/09 09:40:05 [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2019-08-09T09:40:05.546-0400 [DEBUG] plugin: plugin process exited: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84771
2019-08-09T09:40:05.546-0400 [DEBUG] plugin: plugin exited
2019/08/09 09:40:05 [TRACE] [walkPlan] Exiting eval tree: provider.null (close)
2019/08/09 09:40:05 [TRACE] vertex "provider.null (close)": visit complete
2019-08-09T09:40:05.546-0400 [DEBUG] plugin: plugin process exited: path=/usr/local/bin/terraform pid=84770
2019-08-09T09:40:05.546-0400 [DEBUG] plugin: plugin exited
2019/08/09 09:40:05 [TRACE] [walkPlan] Exiting eval tree: provisioner.local-exec (close)
2019/08/09 09:40:05 [TRACE] vertex "provisioner.local-exec (close)": visit complete
2019/08/09 09:40:05 [TRACE] dag/walk: visiting "root"
2019/08/09 09:40:05 [TRACE] vertex "root": starting visit (terraform.graphNodeRoot)
2019/08/09 09:40:05 [TRACE] vertex "root": visit complete
2019/08/09 09:40:05 [DEBUG] command: asking for input: "Do you want to perform these actions?"
2019/08/09 09:40:06 [INFO] backend/local: apply calling Apply
2019/08/09 09:40:06 [INFO] terraform: building graph: GraphTypeApply
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.ConfigTransformer
2019/08/09 09:40:06 [TRACE] ConfigTransformer: Starting for path:
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.DiffTransformer
2019/08/09 09:40:06 [TRACE] DiffTransformer starting
2019/08/09 09:40:06 [TRACE] DiffTransformer: found DeleteThenCreate change for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] DiffTransformer: null_resource.test["foo"] will be represented by null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] DiffTransformer: null_resource.test["foo"] will be represented for destruction by null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [TRACE] DiffTransformer complete
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.DiffTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.OrphanResourceTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.OrphanResourceTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2019/08/09 09:40:06 [TRACE] AttachResourceConfigTransformer: attaching to "null_resource.test[\"foo\"] (destroy)" (*terraform.NodeDestroyResourceInstance) config from main.tf:16,1-32
2019/08/09 09:40:06 [TRACE] AttachResourceConfigTransformer: attaching to "null_resource.test (prepare state)" (*terraform.NodeApplyableResource) config from main.tf:16,1-32
2019/08/09 09:40:06 [TRACE] AttachResourceConfigTransformer: attaching to "null_resource.test[\"foo\"]" (*terraform.NodeApplyableResourceInstance) config from main.tf:16,1-32
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.DestroyEdgeTransformer
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: "null_resource.test[\"foo\"] (destroy)" (*terraform.NodeDestroyResourceInstance) destroys null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: connecting creator "null_resource.test[\"foo\"]" with destroyer "null_resource.test[\"foo\"] (destroy)"
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: constructing temporary graph for analysis of references, starting from:
null_resource.test["foo"] - *terraform.NodeAbstractResourceInstance
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.LocalTransformer on temporary graph
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.OutputTransformer on temporary graph
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.AttachResourceConfigTransformer on temporary graph
2019/08/09 09:40:06 [TRACE] AttachResourceConfigTransformer: attaching to "null_resource.test[\"foo\"]" (*terraform.NodeAbstractResourceInstance) config from main.tf:16,1-32
2019/08/09 09:40:06 [TRACE] AttachResourceConfigTransformer: attaching to "null_resource.test[\"foo\"] (destroy)" (*terraform.NodeDestroyResourceInstance) config from main.tf:16,1-32
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.AttachStateTransformer on temporary graph
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.RootVariableTransformer on temporary graph
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.ModuleVariableTransformer on temporary graph
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.graphTransformerMulti on temporary graph
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
null_resource.test["foo"] - *terraform.NodeAbstractResourceInstance
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2019/08/09 09:40:06 [DEBUG] adding implicit provider configuration provider.null, implied first by null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
null_resource.test["foo"] - *terraform.NodeAbstractResourceInstance
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
provider.null - *terraform.NodeApplyableProvider
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2019/08/09 09:40:06 [TRACE] ProviderTransformer: null_resource.test["foo"] is provided by provider.null or inherited equivalent
2019/08/09 09:40:06 [TRACE] ProviderTransformer: null_resource.test["foo"] (destroy) is provided by provider.null or inherited equivalent
2019/08/09 09:40:06 [TRACE] ProviderTransformer: exact match for provider.null serving null_resource.test["foo"]
2019/08/09 09:40:06 [DEBUG] ProviderTransformer: "null_resource.test[\"foo\"]" (*terraform.NodeAbstractResourceInstance) needs provider.null
2019/08/09 09:40:06 [TRACE] ProviderTransformer: exact match for provider.null serving null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [DEBUG] ProviderTransformer: "null_resource.test[\"foo\"] (destroy)" (*terraform.NodeDestroyResourceInstance) needs provider.null
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
null_resource.test["foo"] - *terraform.NodeAbstractResourceInstance
  provider.null - *terraform.NodeApplyableProvider
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
provider.null - *terraform.NodeApplyableProvider
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.AttachSchemaTransformer on temporary graph
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching resource schema to null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching provisioner "local-exec" config schema to null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching resource schema to null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching provisioner "local-exec" config schema to null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.null
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: running *terraform.ReferenceTransformer on temporary graph
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "provider.null" references: []
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "null_resource.test[\"foo\"]" references: [var.test]
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "null_resource.test[\"foo\"] (destroy)" references: []
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "var.test" references: []
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: temporary reference graph:
null_resource.test["foo"]
  provider.null
  var.test
null_resource.test["foo"] (destroy)
  provider.null
provider.null
var.test
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: creation node "null_resource.test[\"foo\"]" references [var.test provider.null]
2019/08/09 09:40:06 [TRACE] DestroyEdgeTransformer: creation node "null_resource.test[\"foo\"] (destroy)" references [provider.null]
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.DestroyEdgeTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.CBDEdgeTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.CBDEdgeTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2019/08/09 09:40:06 [TRACE] MissingProviderTransformer: added implicit provisioner .local-exec, first implied by null_resource.test (prepare state)
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
provisioner.local-exec - *terraform.NodeProvisioner
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2019/08/09 09:40:06 [TRACE] ProvisionerTransformer: null_resource.test (prepare state) is provisioned by .local-exec ("provisioner.local-exec")
2019/08/09 09:40:06 [TRACE] ProvisionerTransformer: null_resource.test["foo"] is provisioned by .local-exec ("provisioner.local-exec")
2019/08/09 09:40:06 [TRACE] ProvisionerTransformer: null_resource.test["foo"] (destroy) is provisioned by .local-exec ("provisioner.local-exec")
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.ProvisionerTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec - *terraform.NodeProvisioner
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.RootVariableTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.LocalTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.OutputTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2019/08/09 09:40:06 [DEBUG] adding implicit provider configuration provider.null, implied first by null_resource.test (prepare state)
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2019/08/09 09:40:06 [TRACE] ProviderTransformer: null_resource.test["foo"] is provided by provider.null or inherited equivalent
2019/08/09 09:40:06 [TRACE] ProviderTransformer: null_resource.test["foo"] (destroy) is provided by provider.null or inherited equivalent
2019/08/09 09:40:06 [TRACE] ProviderTransformer: null_resource.test (prepare state) is provided by provider.null or inherited equivalent
2019/08/09 09:40:06 [TRACE] ProviderTransformer: exact match for provider.null serving null_resource.test["foo"]
2019/08/09 09:40:06 [DEBUG] ProviderTransformer: "null_resource.test[\"foo\"]" (*terraform.NodeApplyableResourceInstance) needs provider.null
2019/08/09 09:40:06 [TRACE] ProviderTransformer: exact match for provider.null serving null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [DEBUG] ProviderTransformer: "null_resource.test[\"foo\"] (destroy)" (*terraform.NodeDestroyResourceInstance) needs provider.null
2019/08/09 09:40:06 [TRACE] ProviderTransformer: exact match for provider.null serving null_resource.test (prepare state)
2019/08/09 09:40:06 [DEBUG] ProviderTransformer: "null_resource.test (prepare state)" (*terraform.NodeApplyableResource) needs provider.null
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2019/08/09 09:40:06 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching resource schema to null_resource.test (prepare state)
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching provisioner "local-exec" config schema to null_resource.test (prepare state)
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching resource schema to null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching provisioner "local-exec" config schema to null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching resource schema to null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching provisioner "local-exec" config schema to null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.null
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "null_resource.test[\"foo\"] (destroy)" references: []
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "provisioner.local-exec" references: []
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "var.test" references: []
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "provider.null" references: []
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "null_resource.test (prepare state)" references: [var.test]
2019/08/09 09:40:06 [DEBUG] ReferenceTransformer: "null_resource.test[\"foo\"]" references: [var.test]
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.CloseProvisionerTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.CloseProvisionerTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.RootTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  provider.null - *terraform.NodeApplyableProvider
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
root - terraform.graphNodeRoot
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  provider.null (close) - *terraform.graphNodeCloseProvider
  provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2019/08/09 09:40:06 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
null_resource.test (prepare state) - *terraform.NodeApplyableResource
  provider.null - *terraform.NodeApplyableProvider
  provisioner.local-exec - *terraform.NodeProvisioner
  var.test - *terraform.NodeRootVariable
null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
  null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
null_resource.test["foo"] (destroy) - *terraform.NodeDestroyResourceInstance
  null_resource.test (prepare state) - *terraform.NodeApplyableResource
provider.null - *terraform.NodeApplyableProvider
provider.null (close) - *terraform.graphNodeCloseProvider
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
provisioner.local-exec - *terraform.NodeProvisioner
provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
  null_resource.test["foo"] - *terraform.NodeApplyableResourceInstance
root - terraform.graphNodeRoot
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  provider.null (close) - *terraform.graphNodeCloseProvider
  provisioner.local-exec (close) - *terraform.graphNodeCloseProvisioner
var.test - *terraform.NodeRootVariable
------
2019/08/09 09:40:06 [DEBUG] Starting graph walk: walkApply
2019/08/09 09:40:06 [TRACE] dag/walk: updating graph
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "root"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "null_resource.test (prepare state)"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "var.test"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "provider.null"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "meta.count-boundary (EachMode fixup)"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "provider.null (close)"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "null_resource.test[\"foo\"] (destroy)"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "provisioner.local-exec (close)"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "provisioner.local-exec"
2019/08/09 09:40:06 [TRACE] dag/walk: added new vertex: "null_resource.test[\"foo\"]"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "root" waiting on "provisioner.local-exec (close)"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "null_resource.test[\"foo\"] (destroy)" waiting on "null_resource.test (prepare state)"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "null_resource.test[\"foo\"]" waiting on "null_resource.test[\"foo\"] (destroy)"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "null_resource.test (prepare state)" waiting on "var.test"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "null_resource.test (prepare state)" waiting on "provider.null"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "meta.count-boundary (EachMode fixup)" waiting on "null_resource.test[\"foo\"]"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "provisioner.local-exec (close)" waiting on "null_resource.test[\"foo\"]"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "null_resource.test (prepare state)" waiting on "provisioner.local-exec"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "root" waiting on "meta.count-boundary (EachMode fixup)"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "root" waiting on "provider.null (close)"
2019/08/09 09:40:06 [TRACE] dag/walk: added edge: "provider.null (close)" waiting on "null_resource.test[\"foo\"]"
2019/08/09 09:40:06 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2019/08/09 09:40:06 [TRACE] dag/walk: dependencies changed for "null_resource.test[\"foo\"] (destroy)", sending new deps
2019/08/09 09:40:06 [TRACE] dag/walk: dependencies changed for "null_resource.test[\"foo\"]", sending new deps
2019/08/09 09:40:06 [TRACE] dag/walk: dependencies changed for "null_resource.test (prepare state)", sending new deps
2019/08/09 09:40:06 [TRACE] dag/walk: dependencies changed for "meta.count-boundary (EachMode fixup)", sending new deps
2019/08/09 09:40:06 [TRACE] dag/walk: dependencies changed for "provisioner.local-exec (close)", sending new deps
2019/08/09 09:40:06 [TRACE] dag/walk: dependencies changed for "provider.null (close)", sending new deps
2019/08/09 09:40:06 [TRACE] dag/walk: visiting "provisioner.local-exec"
2019/08/09 09:40:06 [TRACE] vertex "provisioner.local-exec": starting visit (*terraform.NodeProvisioner)
2019/08/09 09:40:06 [TRACE] vertex "provisioner.local-exec": evaluating
2019/08/09 09:40:06 [TRACE] dag/walk: visiting "provider.null"
2019/08/09 09:40:06 [TRACE] [walkApply] Entering eval tree: provisioner.local-exec
2019/08/09 09:40:06 [TRACE] vertex "provider.null": starting visit (*terraform.NodeApplyableProvider)
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalInitProvisioner
2019/08/09 09:40:06 [TRACE] dag/walk: visiting "var.test"
2019/08/09 09:40:06 [TRACE] vertex "provider.null": evaluating
2019/08/09 09:40:06 [TRACE] [walkApply] Entering eval tree: provider.null
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalInitProvider
2019/08/09 09:40:06 [TRACE] vertex "var.test": starting visit (*terraform.NodeRootVariable)
2019/08/09 09:40:06 [TRACE] vertex "var.test": visit complete
2019-08-09T09:40:06.623-0400 [INFO]  plugin: configuring client automatic mTLS
2019-08-09T09:40:06.623-0400 [DEBUG] plugin: starting plugin: path=/usr/local/bin/terraform args=[/usr/local/bin/terraform, internal-plugin, provisioner, local-exec]
2019-08-09T09:40:06.626-0400 [DEBUG] plugin: plugin started: path=/usr/local/bin/terraform pid=84772
2019-08-09T09:40:06.626-0400 [DEBUG] plugin: waiting for RPC address: path=/usr/local/bin/terraform
2019-08-09T09:40:06.644-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:06 [INFO] Terraform version: 0.12.6
2019-08-09T09:40:06.644-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:06 [INFO] Go runtime version: go1.12.7
2019-08-09T09:40:06.644-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:06 [INFO] CLI args: []string{"/usr/local/bin/terraform", "internal-plugin", "provisioner", "local-exec"}
2019-08-09T09:40:06.644-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:06 [DEBUG] Attempting to open CLI config file: /Users/adarobin/.terraformrc
2019-08-09T09:40:06.644-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:06 Loading CLI configuration from /Users/adarobin/.terraformrc
2019-08-09T09:40:06.645-0400 [DEBUG] plugin.terraform: 2019/08/09 09:40:06 [INFO] CLI command args: []string{"internal-plugin", "provisioner", "local-exec"}
2019-08-09T09:40:06.645-0400 [DEBUG] plugin.terraform: local-exec-provisioner (internal) 2019/08/09 09:40:06 [INFO] Starting provisioner plugin local-exec
2019-08-09T09:40:06.646-0400 [DEBUG] plugin.terraform: plugin address: address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin075035251 network=unix timestamp=2019-08-09T09:40:06.646-0400
2019-08-09T09:40:06.646-0400 [DEBUG] plugin: using plugin: version=5
2019/08/09 09:40:06 [TRACE] [walkApply] Exiting eval tree: provisioner.local-exec
2019/08/09 09:40:06 [TRACE] vertex "provisioner.local-exec": visit complete
2019-08-09T09:40:06.658-0400 [DEBUG] plugin: starting plugin: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 args=[/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4]
2019-08-09T09:40:06.668-0400 [DEBUG] plugin: plugin started: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84773
2019-08-09T09:40:06.668-0400 [DEBUG] plugin: waiting for RPC address: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4
2019-08-09T09:40:06.685-0400 [INFO]  plugin.terraform-provider-null_v2.1.2_x4: configuring server automatic mTLS: timestamp=2019-08-09T09:40:06.685-0400
2019-08-09T09:40:06.724-0400 [DEBUG] plugin: using plugin: version=5
2019-08-09T09:40:06.724-0400 [DEBUG] plugin.terraform-provider-null_v2.1.2_x4: plugin address: network=unix address=/var/folders/qb/c6gzgldx7cxdb33mrtqkzbyc00npm3/T/plugin776102832 timestamp=2019-08-09T09:40:06.723-0400
2019/08/09 09:40:06 [TRACE] BuiltinEvalContext: Initialized "null" provider for provider.null
2019/08/09 09:40:06 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:06 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalConfigProvider
2019/08/09 09:40:06 [TRACE] buildProviderConfig for provider.null: no configuration at all
2019/08/09 09:40:06 [TRACE] GRPCProvider: GetSchema
2019/08/09 09:40:06 [TRACE] GRPCProvider: Configure
2019/08/09 09:40:06 [TRACE] [walkApply] Exiting eval tree: provider.null
2019/08/09 09:40:06 [TRACE] vertex "provider.null": visit complete
2019/08/09 09:40:06 [TRACE] dag/walk: visiting "null_resource.test (prepare state)"
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test (prepare state)": starting visit (*terraform.NodeApplyableResource)
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test (prepare state)": evaluating
2019/08/09 09:40:06 [TRACE] [walkApply] Entering eval tree: null_resource.test (prepare state)
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalWriteResourceState
2019/08/09 09:40:06 [TRACE] [walkApply] Exiting eval tree: null_resource.test (prepare state)
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test (prepare state)": visit complete
2019/08/09 09:40:06 [TRACE] dag/walk: visiting "null_resource.test[\"foo\"] (destroy)"
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test[\"foo\"] (destroy)": starting visit (*terraform.NodeDestroyResourceInstance)
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test[\"foo\"] (destroy)": evaluating
2019/08/09 09:40:06 [TRACE] [walkApply] Entering eval tree: null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalReadDiff
2019/08/09 09:40:06 [TRACE] EvalReadDiff: Read DeleteThenCreate change from plan for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalReduceDiff
2019/08/09 09:40:06 [TRACE] EvalReduceDiff: null_resource.test["foo"] change simplified from DeleteThenCreate to Delete for destroy node
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalReadState
2019/08/09 09:40:06 [TRACE] EvalReadState: reading state for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] UpgradeResourceState: schema version of null_resource.test["foo"] is still 0; calling provider "null" for any other minor fixups
2019/08/09 09:40:06 [TRACE] GRPCProvider: UpgradeResourceState
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalRequireState
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalApplyPre
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalApply
2019/08/09 09:40:06 [DEBUG] null_resource.test["foo"]: applying the planned Delete change
2019/08/09 09:40:06 [TRACE] GRPCProvider: ApplyResourceChange
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalWriteState
2019/08/09 09:40:06 [TRACE] EvalWriteState: removing state object for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalApplyPost
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalUpdateStateHook
2019/08/09 09:40:06 [TRACE] statemgr.Filesystem: creating backup snapshot at terraform.tfstate.backup
2019/08/09 09:40:06 [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 24
2019/08/09 09:40:06 [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2019/08/09 09:40:06 [TRACE] [walkApply] Exiting eval tree: null_resource.test["foo"] (destroy)
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test[\"foo\"] (destroy)": visit complete
2019/08/09 09:40:06 [TRACE] dag/walk: visiting "null_resource.test[\"foo\"]"
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test[\"foo\"]": starting visit (*terraform.NodeApplyableResourceInstance)
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test[\"foo\"]": evaluating
2019/08/09 09:40:06 [TRACE] [walkApply] Entering eval tree: null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalSequence
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalReadDiff
2019/08/09 09:40:06 [TRACE] EvalReadDiff: Read DeleteThenCreate change from plan for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalReadState
2019/08/09 09:40:06 [TRACE] EvalReadState: reading state for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] EvalReadState: no state present for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalReadDiff
2019/08/09 09:40:06 [TRACE] EvalReadDiff: Read DeleteThenCreate change from plan for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalDiff
2019/08/09 09:40:06 [TRACE] Re-validating config for "null_resource.test[\"foo\"]"
2019/08/09 09:40:06 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2019/08/09 09:40:06 [TRACE] GRPCProvider: PlanResourceChange
2019/08/09 09:40:06 [TRACE] EvalDiff: null_resource.test["foo"] treating Create change as DeleteThenCreate change to match with earlier plan
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalCheckPlannedChange
2019/08/09 09:40:06 [TRACE] EvalCheckPlannedChange: Verifying that actual change (action DeleteThenCreate) matches planned change (action DeleteThenCreate)
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalReadState
2019/08/09 09:40:06 [TRACE] EvalReadState: reading state for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] EvalReadState: no state present for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalReduceDiff
2019/08/09 09:40:06 [TRACE] EvalReduceDiff: null_resource.test["foo"] change simplified from DeleteThenCreate to Create for apply node
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: terraform.EvalNoop
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalApplyPre
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalApply
2019/08/09 09:40:06 [DEBUG] null_resource.test["foo"]: applying the planned Create change
2019/08/09 09:40:06 [TRACE] GRPCProvider: ApplyResourceChange
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalMaybeTainted
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalWriteState
2019/08/09 09:40:06 [TRACE] EvalWriteState: writing current state object for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalApplyProvisioners
2019/08/09 09:40:06 [TRACE] EvalApplyProvisioners: provisioning null_resource.test["foo"] with "local-exec"
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: panic: ConfigValueFromHCL2Block used with unknown value
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform:
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: goroutine 12 [running]:
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: github.com/hashicorp/terraform/config/hcl2shim.ConfigValueFromHCL2Block(0x2e294e0, 0xc0001b4000, 0x26ac700, 0x408a440, 0xc0003b8020, 0x2e06de0)
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: 	/private/tmp/terraform-20190731-89888-r3s7an/terraform-0.12.6/src/github.com/hashicorp/terraform/config/hcl2shim/values.go:41 +0x117b
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: github.com/hashicorp/terraform/terraform.NewResourceConfigShimmed(0x2e294e0, 0xc0001b4000, 0x26ac700, 0x408a440, 0xc0003b8020, 0x2e294e0)
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: 	/private/tmp/terraform-20190731-89888-r3s7an/terraform-0.12.6/src/github.com/hashicorp/terraform/terraform/resource.go:229 +0xa8
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: github.com/hashicorp/terraform/helper/plugin.(*GRPCProvisionerServer).ProvisionResource(0xc0000c4010, 0xc0004ca060, 0x2e3e880, 0xc0003b8010, 0xc0000c4010, 0x20)
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: 	/private/tmp/terraform-20190731-89888-r3s7an/terraform-0.12.6/src/github.com/hashicorp/terraform/helper/plugin/grpc_provisioner.go:112 +0x19f
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: github.com/hashicorp/terraform/internal/tfplugin5._Provisioner_ProvisionResource_Handler(0x2758100, 0xc0000c4010, 0x2e3bdc0, 0xc0003ba000, 0x408aa78, 0xc0002d8300)
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: 	/private/tmp/terraform-20190731-89888-r3s7an/terraform-0.12.6/src/github.com/hashicorp/terraform/internal/tfplugin5/tfplugin5.pb.go:3470 +0x109
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: github.com/hashicorp/terraform/vendor/google.golang.org/grpc.(*Server).processStreamingRPC(0xc000534600, 0x2e461a0, 0xc000091680, 0xc0002d8300, 0xc00049f860, 0x40395c0, 0x0, 0x0, 0x0)
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: 	/private/tmp/terraform-20190731-89888-r3s7an/terraform-0.12.6/src/github.com/hashicorp/terraform/vendor/google.golang.org/grpc/server.go:1170 +0xacd
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: github.com/hashicorp/terraform/vendor/google.golang.org/grpc.(*Server).handleStream(0xc000534600, 0x2e461a0, 0xc000091680, 0xc0002d8300, 0x0)
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: 	/private/tmp/terraform-20190731-89888-r3s7an/terraform-0.12.6/src/github.com/hashicorp/terraform/vendor/google.golang.org/grpc/server.go:1249 +0xcbe
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: github.com/hashicorp/terraform/vendor/google.golang.org/grpc.(*Server).serveStreams.func1.1(0xc0000d64e0, 0xc000534600, 0x2e461a0, 0xc000091680, 0xc0002d8300)
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: 	/private/tmp/terraform-20190731-89888-r3s7an/terraform-0.12.6/src/github.com/hashicorp/terraform/vendor/google.golang.org/grpc/server.go:685 +0x9f
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: created by github.com/hashicorp/terraform/vendor/google.golang.org/grpc.(*Server).serveStreams.func1
2019-08-09T09:40:06.836-0400 [DEBUG] plugin.terraform: 	/private/tmp/terraform-20190731-89888-r3s7an/terraform-0.12.6/src/github.com/hashicorp/terraform/vendor/google.golang.org/grpc/server.go:683 +0xa1
2019/08/09 09:40:06 [WARN] Errors while provisioning null_resource.test["foo"] with "local-exec", so aborting
2019/08/09 09:40:06 [TRACE] EvalApplyProvisioners: null_resource.test["foo"] provisioning failed, but we will continue anyway at the caller's request
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalMaybeTainted
2019/08/09 09:40:06 [TRACE] EvalMaybeTainted: null_resource.test["foo"] encountered an error during creation, so it is now marked as tainted
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalWriteState
2019/08/09 09:40:06 [TRACE] EvalWriteState: writing current state object for null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalIf
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalWriteDiff
2019/08/09 09:40:06 [TRACE] <root>: eval: *terraform.EvalApplyPost
2019/08/09 09:40:06 [ERROR] <root>: eval: *terraform.EvalApplyPost, err: 1 error occurred:
	* 2 problems:

- Reference to "each" in context without for_each: The "each" object can be used only in "resource" blocks, and only when the "for_each" argument is set.
- rpc error: code = Unavailable desc = transport is closing

2019/08/09 09:40:06 [ERROR] <root>: eval: *terraform.EvalSequence, err: 2 problems:

- Reference to "each" in context without for_each: The "each" object can be used only in "resource" blocks, and only when the "for_each" argument is set.
- rpc error: code = Unavailable desc = transport is closing
2019/08/09 09:40:06 [TRACE] [walkApply] Exiting eval tree: null_resource.test["foo"]
2019/08/09 09:40:06 [TRACE] vertex "null_resource.test[\"foo\"]": visit complete
2019/08/09 09:40:06 [TRACE] dag/walk: upstream of "meta.count-boundary (EachMode fixup)" errored, so skipping
2019/08/09 09:40:06 [TRACE] dag/walk: upstream of "provider.null (close)" errored, so skipping
2019/08/09 09:40:06 [TRACE] dag/walk: upstream of "provisioner.local-exec (close)" errored, so skipping
2019/08/09 09:40:06 [TRACE] dag/walk: upstream of "root" errored, so skipping
2019/08/09 09:40:06 [TRACE] statemgr.Filesystem: have already backed up original terraform.tfstate to terraform.tfstate.backup on a previous write
2019/08/09 09:40:06 [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 25
2019/08/09 09:40:06 [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2019-08-09T09:40:06.841-0400 [DEBUG] plugin: plugin process exited: path=/usr/local/bin/terraform pid=84772 error="exit status 2"
2019/08/09 09:40:06 [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2019/08/09 09:40:06 [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
2019-08-09T09:40:06.855-0400 [DEBUG] plugin: plugin exited
2019-08-09T09:40:06.858-0400 [DEBUG] plugin: plugin process exited: path=/Users/adarobin/Code/test-terraform-billing/.terraform/plugins/darwin_amd64/terraform-provider-null_v2.1.2_x4 pid=84773
2019-08-09T09:40:06.859-0400 [DEBUG] plugin: plugin exited