terraform-bug-report-01

tf_apply_debug.log

2019/09/04 18:26:38 [INFO] Terraform version: 0.12.7  
2019/09/04 18:26:38 [INFO] Go runtime version: go1.12.9
2019/09/04 18:26:38 [INFO] CLI args: []string{"/usr/local/bin/terraform", "apply"}
2019/09/04 18:26:38 [DEBUG] Attempting to open CLI config file: /home/tknoeppl/.terraformrc
2019/09/04 18:26:38 [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2019/09/04 18:26:38 [INFO] CLI command args: []string{"apply"}
2019/09/04 18:26:38 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2019/09/04 18:26:38 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2019/09/04 18:26:38 [DEBUG] New state was assigned lineage "cdc059ec-19ff-682c-dd12-716beaae90b9"
2019/09/04 18:26:38 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2019/09/04 18:26:38 [TRACE] Meta.Backend: instantiated backend of type <nil>
2019/09/04 18:26:38 [DEBUG] checking for provider in "."
2019/09/04 18:26:38 [DEBUG] checking for provider in "/usr/local/bin"
2019/09/04 18:26:38 [DEBUG] checking for provider in ".terraform/plugins/linux_amd64"
2019/09/04 18:26:38 [DEBUG] found provider "terraform-provider-local_v1.3.0_x4"
2019/09/04 18:26:38 [DEBUG] found valid plugin: "local", "1.3.0", "/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4"
2019/09/04 18:26:38 [DEBUG] checking for provisioner in "."
2019/09/04 18:26:38 [DEBUG] checking for provisioner in "/usr/local/bin"
2019/09/04 18:26:38 [DEBUG] checking for provisioner in ".terraform/plugins/linux_amd64"
2019/09/04 18:26:38 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2019/09/04 18:26:38 [INFO] backend/local: starting Apply operation
2019/09/04 18:26:38 [TRACE] backend/local: requesting state manager for workspace "default"
2019/09/04 18:26:38 [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/09/04 18:26:38 [TRACE] backend/local: requesting state lock for workspace "default"
2019/09/04 18:26:38 [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2019/09/04 18:26:38 [TRACE] statemgr.Filesystem: existing snapshot has lineage "ca980675-749b-769c-fc11-6ff0769e23e5" serial 2
2019/09/04 18:26:38 [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2019/09/04 18:26:38 [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2019/09/04 18:26:38 [TRACE] backend/local: reading remote state for workspace "default"
2019/09/04 18:26:38 [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2019/09/04 18:26:38 [TRACE] statemgr.Filesystem: read snapshot with lineage "ca980675-749b-769c-fc11-6ff0769e23e5" serial 2
2019/09/04 18:26:38 [TRACE] backend/local: retrieving local state snapshot for workspace "default"
2019/09/04 18:26:38 [TRACE] backend/local: building context for current working directory
2019/09/04 18:26:38 [TRACE] terraform.NewContext: starting
2019/09/04 18:26:38 [TRACE] terraform.NewContext: resolving provider version selections
2019/09/04 18:26:38 [TRACE] terraform.NewContext: loading provider schemas
2019/09/04 18:26:38 [TRACE] LoadSchemas: retrieving schema for provider type "local"
2019-09-04T18:26:38.641+0200 [INFO]  plugin: configuring client automatic mTLS
2019-09-04T18:26:38.662+0200 [DEBUG] plugin: starting plugin: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 args=[/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4]
2019-09-04T18:26:38.662+0200 [DEBUG] plugin: plugin started: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 pid=9574
2019-09-04T18:26:38.662+0200 [DEBUG] plugin: waiting for RPC address: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4
2019-09-04T18:26:38.667+0200 [INFO]  plugin.terraform-provider-local_v1.3.0_x4: configuring server automatic mTLS: timestamp=2019-09-04T18:26:38.667+0200
2019-09-04T18:26:38.690+0200 [DEBUG] plugin.terraform-provider-local_v1.3.0_x4: plugin address: address=/tmp/plugin280720502 network=unix timestamp=2019-09-04T18:26:38.690+0200
2019-09-04T18:26:38.690+0200 [DEBUG] plugin: using plugin: version=5
2019/09/04 18:26:38 [TRACE] GRPCProvider: GetSchema
2019/09/04 18:26:38 [TRACE] GRPCProvider: Close
2019-09-04T18:26:38.736+0200 [DEBUG] plugin: plugin process exited: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 pid=9574
2019-09-04T18:26:38.736+0200 [DEBUG] plugin: plugin exited
2019/09/04 18:26:38 [TRACE] terraform.NewContext: complete
2019/09/04 18:26:38 [TRACE] backend/local: finished building terraform.Context
2019/09/04 18:26:38 [TRACE] backend/local: requesting interactive input, if necessary
2019/09/04 18:26:38 [TRACE] Context.Input: Prompting for variables
2019/09/04 18:26:38 [TRACE] Context.Input: Prompting for provider arguments
2019/09/04 18:26:38 [TRACE] Context.Input: Provider provider.local implied by resource block at main.tf:37,1-38
2019/09/04 18:26:38 [TRACE] Context.Input: Input for provider.local: map[string]cty.Value{}
2019/09/04 18:26:38 [TRACE] backend/local: running validation operation
2019/09/04 18:26:38 [INFO] terraform: building graph: GraphTypeValidate
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ConfigTransformer
2019/09/04 18:26:38 [TRACE] ConfigTransformer: Starting for path: 
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
local_file.my-test-files - *terraform.NodeValidatableResource
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.LocalTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.LocalTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.OutputTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.OutputTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
output.test-output - *terraform.NodeApplyableOutput
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.StateTransformer
2019/09/04 18:26:38 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2019/09/04 18:26:38 [TRACE] AttachResourceConfigTransformer: attaching to "local_file.my-test-files" (*terraform.NodeValidatableResource) config from main.tf:37,1-38
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.MissingProvisionerTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.MissingProvisionerTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ProvisionerTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ProvisionerTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
output.test-output - *terraform.NodeApplyableOutput
------
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2019/09/04 18:26:38 [DEBUG] adding implicit provider configuration provider.local, implied first by local_file.my-test-files
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
output.test-output - *terraform.NodeApplyableOutput
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2019/09/04 18:26:38 [TRACE] ProviderTransformer: local_file.my-test-files is provided by provider.local or inherited equivalent
2019/09/04 18:26:38 [TRACE] ProviderTransformer: exact match for provider.local serving local_file.my-test-files
2019/09/04 18:26:38 [DEBUG] ProviderTransformer: "local_file.my-test-files" (*terraform.NodeValidatableResource) needs provider.local
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2019/09/04 18:26:38 [TRACE] AttachSchemaTransformer: attaching resource schema to local_file.my-test-files
2019/09/04 18:26:38 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.local
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "provider.local" references: []
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "local_file.my-test-files" references: [local.names local.names]
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "local.names" references: []
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "output.test-output" references: [local_file.my-test-files local.names]
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeValidatableResource
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeValidatableResource
  output.test-output - *terraform.NodeApplyableOutput
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeValidatableResource
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeValidatableResource
  output.test-output - *terraform.NodeApplyableOutput
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeValidatableResource
provider.local - *terraform.NodeApplyableProvider
provider.local (close) - *terraform.graphNodeCloseProvider
  local_file.my-test-files - *terraform.NodeValidatableResource
  provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.CloseProvisionerTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.CloseProvisionerTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.RootTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeValidatableResource
  output.test-output - *terraform.NodeApplyableOutput
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeValidatableResource
provider.local - *terraform.NodeApplyableProvider
provider.local (close) - *terraform.graphNodeCloseProvider
  local_file.my-test-files - *terraform.NodeValidatableResource
  provider.local - *terraform.NodeApplyableProvider
root - terraform.graphNodeRoot
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  provider.local (close) - *terraform.graphNodeCloseProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeValidatableResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  output.test-output - *terraform.NodeApplyableOutput
output.test-output - *terraform.NodeApplyableOutput
  local_file.my-test-files - *terraform.NodeValidatableResource
provider.local - *terraform.NodeApplyableProvider
provider.local (close) - *terraform.graphNodeCloseProvider
  local_file.my-test-files - *terraform.NodeValidatableResource
root - terraform.graphNodeRoot
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
  provider.local (close) - *terraform.graphNodeCloseProvider
------
2019/09/04 18:26:38 [DEBUG] Starting graph walk: walkValidate
2019/09/04 18:26:38 [TRACE] dag/walk: updating graph
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "provider.local"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "meta.count-boundary (EachMode fixup)"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "provider.local (close)"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "root"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "local_file.my-test-files"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "local.names"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "output.test-output"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "meta.count-boundary (EachMode fixup)" waiting on "output.test-output"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "root" waiting on "meta.count-boundary (EachMode fixup)"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "output.test-output" waiting on "local_file.my-test-files"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "provider.local (close)" waiting on "local_file.my-test-files"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "root" waiting on "provider.local (close)"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "local_file.my-test-files" waiting on "provider.local"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "local_file.my-test-files" waiting on "local.names"
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "meta.count-boundary (EachMode fixup)", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "output.test-output", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "provider.local (close)", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "local_file.my-test-files", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "local.names"
2019/09/04 18:26:38 [TRACE] vertex "local.names": starting visit (*terraform.NodeLocal)
2019/09/04 18:26:38 [TRACE] vertex "local.names": evaluating
2019/09/04 18:26:38 [TRACE] [walkValidate] Entering eval tree: local.names
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalLocal
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "provider.local"
2019/09/04 18:26:38 [TRACE] vertex "provider.local": starting visit (*terraform.NodeApplyableProvider)
2019/09/04 18:26:38 [TRACE] vertex "provider.local": evaluating
2019/09/04 18:26:38 [TRACE] [walkValidate] Entering eval tree: provider.local
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalInitProvider
2019/09/04 18:26:38 [TRACE] [walkValidate] Exiting eval tree: local.names
2019/09/04 18:26:38 [TRACE] vertex "local.names": visit complete
2019-09-04T18:26:38.737+0200 [INFO]  plugin: configuring client automatic mTLS
2019-09-04T18:26:38.758+0200 [DEBUG] plugin: starting plugin: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 args=[/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4]
2019-09-04T18:26:38.758+0200 [DEBUG] plugin: plugin started: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 pid=9593
2019-09-04T18:26:38.758+0200 [DEBUG] plugin: waiting for RPC address: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4
2019-09-04T18:26:38.763+0200 [INFO]  plugin.terraform-provider-local_v1.3.0_x4: configuring server automatic mTLS: timestamp=2019-09-04T18:26:38.763+0200
2019-09-04T18:26:38.786+0200 [DEBUG] plugin.terraform-provider-local_v1.3.0_x4: plugin address: address=/tmp/plugin336906908 network=unix timestamp=2019-09-04T18:26:38.786+0200
2019-09-04T18:26:38.786+0200 [DEBUG] plugin: using plugin: version=5
2019/09/04 18:26:38 [TRACE] BuiltinEvalContext: Initialized "local" provider for provider.local
2019/09/04 18:26:38 [TRACE] <root>: eval: terraform.EvalNoop
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalValidateProvider
2019/09/04 18:26:38 [TRACE] buildProviderConfig for provider.local: no configuration at all
2019/09/04 18:26:38 [TRACE] GRPCProvider: GetSchema
2019/09/04 18:26:38 [TRACE] GRPCProvider: PrepareProviderConfig
2019/09/04 18:26:38 [TRACE] <root>: eval: terraform.EvalNoop
2019/09/04 18:26:38 [TRACE] <root>: eval: terraform.EvalNoop
2019/09/04 18:26:38 [TRACE] [walkValidate] Exiting eval tree: provider.local
2019/09/04 18:26:38 [TRACE] vertex "provider.local": visit complete
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "local_file.my-test-files"
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files": starting visit (*terraform.NodeValidatableResource)
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files": evaluating
2019/09/04 18:26:38 [TRACE] [walkValidate] Entering eval tree: local_file.my-test-files
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalValidateResource
2019/09/04 18:26:38 [TRACE] GRPCProvider: ValidateResourceTypeConfig
2019/09/04 18:26:38 [TRACE] [walkValidate] Exiting eval tree: local_file.my-test-files
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files": visit complete
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "output.test-output"
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "provider.local (close)"
2019/09/04 18:26:38 [TRACE] vertex "output.test-output": starting visit (*terraform.NodeApplyableOutput)
2019/09/04 18:26:38 [TRACE] vertex "provider.local (close)": starting visit (*terraform.graphNodeCloseProvider)
2019/09/04 18:26:38 [TRACE] vertex "output.test-output": evaluating
2019/09/04 18:26:38 [TRACE] vertex "provider.local (close)": evaluating
2019/09/04 18:26:38 [TRACE] [walkValidate] Entering eval tree: output.test-output
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] [walkValidate] Entering eval tree: provider.local (close)
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalCloseProvider
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalWriteOutput
2019/09/04 18:26:38 [TRACE] GRPCProvider: Close
2019/09/04 18:26:38 [TRACE] EvalWriteOutput: Removing output.test-output from state (it is now null)
2019/09/04 18:26:38 [TRACE] EvalWriteOutput: Saving Create change for output.test-output in changeset
2019/09/04 18:26:38 [TRACE] [walkValidate] Exiting eval tree: output.test-output
2019/09/04 18:26:38 [TRACE] vertex "output.test-output": visit complete
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "meta.count-boundary (EachMode fixup)"
2019/09/04 18:26:38 [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2019/09/04 18:26:38 [TRACE] vertex "meta.count-boundary (EachMode fixup)": evaluating
2019/09/04 18:26:38 [TRACE] [walkValidate] Entering eval tree: meta.count-boundary (EachMode fixup)
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalCountFixZeroOneBoundaryGlobal
2019/09/04 18:26:38 [TRACE] [walkValidate] Exiting eval tree: meta.count-boundary (EachMode fixup)
2019/09/04 18:26:38 [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2019-09-04T18:26:38.834+0200 [DEBUG] plugin: plugin process exited: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 pid=9593
2019-09-04T18:26:38.834+0200 [DEBUG] plugin: plugin exited
2019/09/04 18:26:38 [TRACE] [walkValidate] Exiting eval tree: provider.local (close)
2019/09/04 18:26:38 [TRACE] vertex "provider.local (close)": visit complete
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "root"
2019/09/04 18:26:38 [TRACE] vertex "root": starting visit (terraform.graphNodeRoot)
2019/09/04 18:26:38 [TRACE] vertex "root": visit complete
2019/09/04 18:26:38 [INFO] backend/local: apply calling Refresh
2019/09/04 18:26:38 [INFO] terraform: building graph: GraphTypeRefresh
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ConfigTransformer
2019/09/04 18:26:38 [TRACE] ConfigTransformer: Starting for path: 
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ConfigTransformer
2019/09/04 18:26:38 [TRACE] ConfigTransformer: Starting for path: 
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ConfigTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.StateTransformer
2019/09/04 18:26:38 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2019/09/04 18:26:38 [TRACE] AttachResourceConfigTransformer: attaching to "local_file.my-test-files" (*terraform.NodeRefreshableManagedResource) config from main.tf:37,1-38
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.LocalTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.LocalTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.OutputTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.OutputTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
output.test-output - *terraform.NodeApplyableOutput
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
output.test-output - *terraform.NodeApplyableOutput
------
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2019/09/04 18:26:38 [DEBUG] adding implicit provider configuration provider.local, implied first by local_file.my-test-files
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
output.test-output - *terraform.NodeApplyableOutput
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2019/09/04 18:26:38 [TRACE] ProviderTransformer: local_file.my-test-files is provided by provider.local or inherited equivalent
2019/09/04 18:26:38 [TRACE] ProviderTransformer: exact match for provider.local serving local_file.my-test-files
2019/09/04 18:26:38 [DEBUG] ProviderTransformer: "local_file.my-test-files" (*terraform.NodeRefreshableManagedResource) needs provider.local
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ParentProviderTransformer
2019/09/04 18:26:38 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ParentProviderTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2019/09/04 18:26:38 [TRACE] AttachSchemaTransformer: attaching resource schema to local_file.my-test-files
2019/09/04 18:26:38 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider.local
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "local_file.my-test-files" references: [local.names local.names]
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "local.names" references: []
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "output.test-output" references: [local_file.my-test-files local.names]
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "provider.local" references: []
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeRefreshableManagedResource
provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeRefreshableManagedResource
provider.local - *terraform.NodeApplyableProvider
provider.local (close) - *terraform.graphNodeCloseProvider
  local_file.my-test-files - *terraform.NodeRefreshableManagedResource
  provider.local - *terraform.NodeApplyableProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.RootTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
  local.names - *terraform.NodeLocal
  local_file.my-test-files - *terraform.NodeRefreshableManagedResource
provider.local - *terraform.NodeApplyableProvider
provider.local (close) - *terraform.graphNodeCloseProvider
  local_file.my-test-files - *terraform.NodeRefreshableManagedResource
  provider.local - *terraform.NodeApplyableProvider
root - terraform.graphNodeRoot
  output.test-output - *terraform.NodeApplyableOutput
  provider.local (close) - *terraform.graphNodeCloseProvider
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
local.names - *terraform.NodeLocal
local_file.my-test-files - *terraform.NodeRefreshableManagedResource
  local.names - *terraform.NodeLocal
  provider.local - *terraform.NodeApplyableProvider
output.test-output - *terraform.NodeApplyableOutput
  local_file.my-test-files - *terraform.NodeRefreshableManagedResource
provider.local - *terraform.NodeApplyableProvider
provider.local (close) - *terraform.graphNodeCloseProvider
  local_file.my-test-files - *terraform.NodeRefreshableManagedResource
root - terraform.graphNodeRoot
  output.test-output - *terraform.NodeApplyableOutput
  provider.local (close) - *terraform.graphNodeCloseProvider
------
2019/09/04 18:26:38 [DEBUG] Starting graph walk: walkRefresh
2019/09/04 18:26:38 [TRACE] dag/walk: updating graph
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "local.names"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "output.test-output"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "provider.local"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "provider.local (close)"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "root"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "local_file.my-test-files"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "local_file.my-test-files" waiting on "provider.local"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "local_file.my-test-files" waiting on "local.names"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "output.test-output" waiting on "local_file.my-test-files"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "provider.local (close)" waiting on "local_file.my-test-files"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "root" waiting on "output.test-output"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "root" waiting on "provider.local (close)"
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "local_file.my-test-files", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "output.test-output", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "provider.local (close)", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "local.names"
2019/09/04 18:26:38 [TRACE] vertex "local.names": starting visit (*terraform.NodeLocal)
2019/09/04 18:26:38 [TRACE] vertex "local.names": evaluating
2019/09/04 18:26:38 [TRACE] [walkRefresh] Entering eval tree: local.names
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalLocal
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "provider.local"
2019/09/04 18:26:38 [TRACE] vertex "provider.local": starting visit (*terraform.NodeApplyableProvider)
2019/09/04 18:26:38 [TRACE] vertex "provider.local": evaluating
2019/09/04 18:26:38 [TRACE] [walkRefresh] Entering eval tree: provider.local
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalInitProvider
2019/09/04 18:26:38 [TRACE] [walkRefresh] Exiting eval tree: local.names
2019/09/04 18:26:38 [TRACE] vertex "local.names": visit complete
2019-09-04T18:26:38.835+0200 [INFO]  plugin: configuring client automatic mTLS
2019-09-04T18:26:38.859+0200 [DEBUG] plugin: starting plugin: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 args=[/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4]
2019-09-04T18:26:38.859+0200 [DEBUG] plugin: plugin started: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 pid=9608
2019-09-04T18:26:38.859+0200 [DEBUG] plugin: waiting for RPC address: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4
2019-09-04T18:26:38.864+0200 [INFO]  plugin.terraform-provider-local_v1.3.0_x4: configuring server automatic mTLS: timestamp=2019-09-04T18:26:38.864+0200
2019-09-04T18:26:38.889+0200 [DEBUG] plugin: using plugin: version=5
2019-09-04T18:26:38.889+0200 [DEBUG] plugin.terraform-provider-local_v1.3.0_x4: plugin address: address=/tmp/plugin219457645 network=unix timestamp=2019-09-04T18:26:38.889+0200
2019/09/04 18:26:38 [TRACE] BuiltinEvalContext: Initialized "local" provider for provider.local
2019/09/04 18:26:38 [TRACE] <root>: eval: terraform.EvalNoop
2019/09/04 18:26:38 [TRACE] <root>: eval: terraform.EvalNoop
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalConfigProvider
2019/09/04 18:26:38 [TRACE] buildProviderConfig for provider.local: no configuration at all
2019/09/04 18:26:38 [TRACE] GRPCProvider: GetSchema
2019/09/04 18:26:38 [TRACE] GRPCProvider: Configure
2019/09/04 18:26:38 [TRACE] [walkRefresh] Exiting eval tree: provider.local
2019/09/04 18:26:38 [TRACE] vertex "provider.local": visit complete
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "local_file.my-test-files"
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files": starting visit (*terraform.NodeRefreshableManagedResource)
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files": expanding dynamic subgraph
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
local_file.my-test-files[0] - *terraform.NodeRefreshableManagedResourceInstance
local_file.my-test-files[1] - *terraform.NodeRefreshableManagedResourceInstance
local_file.my-test-files[2] - *terraform.NodeRefreshableManagedResourceInstance
------
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.OrphanResourceCountTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.OrphanResourceCountTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.TargetsTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "local_file.my-test-files[2]" references: []
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "local_file.my-test-files[0]" references: []
2019/09/04 18:26:38 [DEBUG] ReferenceTransformer: "local_file.my-test-files[1]" references: []
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2019/09/04 18:26:38 [TRACE] Executing graph transform *terraform.RootTransformer
2019/09/04 18:26:38 [TRACE] Completed graph transform *terraform.RootTransformer with new graph:
local_file.my-test-files[0] - *terraform.NodeRefreshableManagedResourceInstance
local_file.my-test-files[1] - *terraform.NodeRefreshableManagedResourceInstance
local_file.my-test-files[2] - *terraform.NodeRefreshableManagedResourceInstance
root - terraform.graphNodeRoot
  local_file.my-test-files[0] - *terraform.NodeRefreshableManagedResourceInstance
  local_file.my-test-files[1] - *terraform.NodeRefreshableManagedResourceInstance
  local_file.my-test-files[2] - *terraform.NodeRefreshableManagedResourceInstance
------
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files": entering dynamic subgraph
2019/09/04 18:26:38 [TRACE] dag/walk: updating graph
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "local_file.my-test-files[1]"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "local_file.my-test-files[2]"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "root"
2019/09/04 18:26:38 [TRACE] dag/walk: added new vertex: "local_file.my-test-files[0]"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "root" waiting on "local_file.my-test-files[0]"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "root" waiting on "local_file.my-test-files[1]"
2019/09/04 18:26:38 [TRACE] dag/walk: added edge: "root" waiting on "local_file.my-test-files[2]"
2019/09/04 18:26:38 [TRACE] dag/walk: dependencies changed for "root", sending new deps
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "local_file.my-test-files[1]"
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[1]": starting visit (*terraform.NodeRefreshableManagedResourceInstance)
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "local_file.my-test-files[0]"
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "local_file.my-test-files[2]"
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[0]": starting visit (*terraform.NodeRefreshableManagedResourceInstance)
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[2]": starting visit (*terraform.NodeRefreshableManagedResourceInstance)
2019/09/04 18:26:38 [TRACE] NodeRefreshableManagedResourceInstance: local_file.my-test-files[1] will be refreshed
2019/09/04 18:26:38 [TRACE] NodeRefreshableManagedResourceInstance: local_file.my-test-files[2] will be refreshed
2019/09/04 18:26:38 [TRACE] NodeRefreshableManagedResourceInstance: local_file.my-test-files[0] will be refreshed
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[2]": evaluating
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[1]": evaluating
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[0]": evaluating
2019/09/04 18:26:38 [TRACE] [walkRefresh] Entering eval tree: local_file.my-test-files[2]
2019/09/04 18:26:38 [TRACE] [walkRefresh] Entering eval tree: local_file.my-test-files[1]
2019/09/04 18:26:38 [TRACE] [walkRefresh] Entering eval tree: local_file.my-test-files[0]
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalGetProvider
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalReadState
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalReadState
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalReadState
2019/09/04 18:26:38 [TRACE] EvalReadState: reading state for local_file.my-test-files[1]
2019/09/04 18:26:38 [TRACE] EvalReadState: reading state for local_file.my-test-files[2]
2019/09/04 18:26:38 [TRACE] UpgradeResourceState: schema version of local_file.my-test-files[1] is still 0; calling provider "local" for any other minor fixups
2019/09/04 18:26:38 [TRACE] GRPCProvider: UpgradeResourceState
2019/09/04 18:26:38 [TRACE] UpgradeResourceState: schema version of local_file.my-test-files[2] is still 0; calling provider "local" for any other minor fixups
2019/09/04 18:26:38 [TRACE] GRPCProvider: UpgradeResourceState
2019/09/04 18:26:38 [TRACE] EvalReadState: reading state for local_file.my-test-files[0]
2019/09/04 18:26:38 [TRACE] UpgradeResourceState: schema version of local_file.my-test-files[0] is still 0; calling provider "local" for any other minor fixups
2019/09/04 18:26:38 [TRACE] GRPCProvider: UpgradeResourceState
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalRefresh
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalRefresh
local_file.my-test-files[1]: Refreshing state... [id=da39a3ee5e6b4b0d3255bfef95601890afd80709]
local_file.my-test-files[2]: Refreshing state... [id=da39a3ee5e6b4b0d3255bfef95601890afd80709]
local_file.my-test-files[0]: Refreshing state... [id=da39a3ee5e6b4b0d3255bfef95601890afd80709]
2019/09/04 18:26:38 [TRACE] GRPCProvider: ReadResource
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalRefresh
2019/09/04 18:26:38 [TRACE] GRPCProvider: ReadResource
2019/09/04 18:26:38 [TRACE] GRPCProvider: ReadResource
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalWriteState
2019/09/04 18:26:38 [TRACE] EvalWriteState: writing current state object for local_file.my-test-files[1]
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalWriteState
2019/09/04 18:26:38 [TRACE] EvalWriteState: writing current state object for local_file.my-test-files[2]
2019/09/04 18:26:38 [TRACE] [walkRefresh] Exiting eval tree: local_file.my-test-files[1]
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[1]": visit complete
2019/09/04 18:26:38 [TRACE] [walkRefresh] Exiting eval tree: local_file.my-test-files[2]
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[2]": visit complete
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalWriteState
2019/09/04 18:26:38 [TRACE] EvalWriteState: writing current state object for local_file.my-test-files[0]
2019/09/04 18:26:38 [TRACE] [walkRefresh] Exiting eval tree: local_file.my-test-files[0]
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files[0]": visit complete
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "root"
2019/09/04 18:26:38 [TRACE] vertex "root": starting visit (terraform.graphNodeRoot)
2019/09/04 18:26:38 [TRACE] vertex "root": visit complete
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files": dynamic subgraph completed successfully
2019/09/04 18:26:38 [TRACE] vertex "local_file.my-test-files": visit complete
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "provider.local (close)"
2019/09/04 18:26:38 [TRACE] vertex "provider.local (close)": starting visit (*terraform.graphNodeCloseProvider)
2019/09/04 18:26:38 [TRACE] dag/walk: visiting "output.test-output"
2019/09/04 18:26:38 [TRACE] vertex "provider.local (close)": evaluating
2019/09/04 18:26:38 [TRACE] vertex "output.test-output": starting visit (*terraform.NodeApplyableOutput)
2019/09/04 18:26:38 [TRACE] [walkRefresh] Entering eval tree: provider.local (close)
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalCloseProvider
2019/09/04 18:26:38 [TRACE] vertex "output.test-output": evaluating
2019/09/04 18:26:38 [TRACE] GRPCProvider: Close
2019/09/04 18:26:38 [TRACE] [walkRefresh] Entering eval tree: output.test-output
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalSequence
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalOpFilter
2019/09/04 18:26:38 [TRACE] <root>: eval: *terraform.EvalWriteOutput
2019/09/04 18:26:38 [TRACE] GetResourceInstance: local_file.my-test-files has multiple keyed instances
2019/09/04 18:26:38 [ERROR] <root>: eval: *terraform.EvalWriteOutput, err: Error in function call: Call to function "index" failed: item not found.
2019/09/04 18:26:38 [ERROR] <root>: eval: *terraform.EvalOpFilter, err: Error in function call: Call to function "index" failed: item not found.
2019/09/04 18:26:38 [ERROR] <root>: eval: *terraform.EvalSequence, err: Error in function call: Call to function "index" failed: item not found.
2019/09/04 18:26:38 [TRACE] [walkRefresh] Exiting eval tree: output.test-output
2019/09/04 18:26:38 [TRACE] vertex "output.test-output": visit complete
2019-09-04T18:26:38.942+0200 [DEBUG] plugin: plugin process exited: path=/home/tknoeppl/projects/tf-test/main/.terraform/plugins/linux_amd64/terraform-provider-local_v1.3.0_x4 pid=9608
2019-09-04T18:26:38.942+0200 [DEBUG] plugin: plugin exited
2019/09/04 18:26:38 [TRACE] [walkRefresh] Exiting eval tree: provider.local (close)
2019/09/04 18:26:38 [TRACE] vertex "provider.local (close)": visit complete
2019/09/04 18:26:38 [TRACE] dag/walk: upstream of "root" errored, so skipping

Error: Error in function call
2019/09/04 18:26:38 [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info

2019/09/04 18:26:38 [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
  on main.tf line 43, in output "test-output":
  43:   value = index( local_file.my-test-files.*.filename, local.names[0] )
    |----------------
    | local.names[0] is "my-file-1"
    | local_file.my-test-files is tuple with 3 elements

Call to function "index" failed: item not found.