andyroyle/terraform.log

## terraform.log
2022-08-15T14:18:43.510+0100 [INFO]  Terraform version: 1.2.7
2022-08-15T14:18:43.510+0100 [DEBUG] using github.com/hashicorp/go-tfe v1.0.0
2022-08-15T14:18:43.510+0100 [DEBUG] using github.com/hashicorp/hcl/v2 v2.12.0
2022-08-15T14:18:43.510+0100 [DEBUG] using github.com/hashicorp/terraform-config-inspect v0.0.0-20210209133302-4fd17a0faac2
2022-08-15T14:18:43.510+0100 [DEBUG] using github.com/hashicorp/terraform-svchost v0.0.0-20200729002733-f050f53b9734
2022-08-15T14:18:43.510+0100 [DEBUG] using github.com/zclconf/go-cty v1.10.0
2022-08-15T14:18:43.510+0100 [INFO]  Go runtime version: go1.19
2022-08-15T14:18:43.510+0100 [INFO]  CLI args: []string{"terraform", "apply", "--auto-approve"}
2022-08-15T14:18:43.510+0100 [TRACE] Stdout is a terminal of width 158
2022-08-15T14:18:43.510+0100 [TRACE] Stderr is a terminal of width 158
2022-08-15T14:18:43.510+0100 [TRACE] Stdin is a terminal
2022-08-15T14:18:43.511+0100 [DEBUG] Attempting to open CLI config file: /Users/andrewroyle/.terraformrc
2022-08-15T14:18:43.511+0100 [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2022-08-15T14:18:43.513+0100 [DEBUG] checking for credentials in "/Users/andrewroyle/.terraform.d/plugins"
2022-08-15T14:18:43.513+0100 [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2022-08-15T14:18:43.513+0100 [DEBUG] will search for provider plugins in /Users/andrewroyle/.terraform.d/plugins
2022-08-15T14:18:43.514+0100 [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/healx/marklogic v1.0.0 for darwin_arm64 at /Users/andrewroyle/.terraform.d/plugins/registry.terraform.io/healx/marklogic/1.0.0/darwin_arm64
2022-08-15T14:18:43.514+0100 [DEBUG] ignoring non-existing provider search directory /Users/andrewroyle/Library/Application Support/io.terraform/plugins
2022-08-15T14:18:43.514+0100 [DEBUG] ignoring non-existing provider search directory /Library/Application Support/io.terraform/plugins
2022-08-15T14:18:43.515+0100 [INFO]  CLI command args: []string{"apply", "--auto-approve"}
2022-08-15T14:18:43.520+0100 [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2022-08-15T14:18:43.520+0100 [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2022-08-15T14:18:43.520+0100 [DEBUG] New state was assigned lineage "b1deda53-4d02-94f3-6e10-792970322999"
2022-08-15T14:18:43.520+0100 [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2022-08-15T14:18:43.520+0100 [TRACE] Meta.Backend: instantiated backend of type <nil>
2022-08-15T14:18:43.520+0100 [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2022-08-15T14:18:43.521+0100 [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/hashicorp/local v2.2.3 for darwin_arm64 at .terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64
2022-08-15T14:18:43.521+0100 [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/hashicorp/null v3.1.1 for darwin_arm64 at .terraform/providers/registry.terraform.io/hashicorp/null/3.1.1/darwin_arm64
2022-08-15T14:18:43.521+0100 [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/hashicorp/time v0.8.0 for darwin_arm64 at .terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64
2022-08-15T14:18:43.521+0100 [TRACE] providercache.fillMetaCache: including .terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64 as a candidate package for registry.terraform.io/hashicorp/local 2.2.3
2022-08-15T14:18:43.521+0100 [TRACE] providercache.fillMetaCache: including .terraform/providers/registry.terraform.io/hashicorp/null/3.1.1/darwin_arm64 as a candidate package for registry.terraform.io/hashicorp/null 3.1.1
2022-08-15T14:18:43.521+0100 [TRACE] providercache.fillMetaCache: including .terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64 as a candidate package for registry.terraform.io/hashicorp/time 0.8.0
2022-08-15T14:18:43.536+0100 [TRACE] providercache.fillMetaCache: using cached result from previous scan of .terraform/providers
2022-08-15T14:18:43.548+0100 [TRACE] providercache.fillMetaCache: using cached result from previous scan of .terraform/providers
2022-08-15T14:18:43.561+0100 [DEBUG] checking for provisioner in "."
2022-08-15T14:18:43.563+0100 [DEBUG] checking for provisioner in "/opt/homebrew/bin"
2022-08-15T14:18:43.563+0100 [DEBUG] checking for provisioner in "/Users/andrewroyle/.terraform.d/plugins"
2022-08-15T14:18:43.563+0100 [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2022-08-15T14:18:43.564+0100 [INFO]  backend/local: starting Apply operation
2022-08-15T14:18:43.564+0100 [TRACE] backend/local: requesting state manager for workspace "default"
2022-08-15T14:18:43.565+0100 [TRACE] backend/local: state manager for workspace "default" will:
 - read initial snapshot from terraform.tfstate
 - write new snapshots to terraform.tfstate
 - create any backup at terraform.tfstate.backup
2022-08-15T14:18:43.565+0100 [TRACE] backend/local: requesting state lock for workspace "default"
2022-08-15T14:18:43.567+0100 [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2022-08-15T14:18:43.568+0100 [TRACE] statemgr.Filesystem: existing snapshot has lineage "fef22b26-9eee-6416-dd98-9b658cb275de" serial 32
2022-08-15T14:18:43.568+0100 [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2022-08-15T14:18:43.568+0100 [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2022-08-15T14:18:43.568+0100 [TRACE] backend/local: reading remote state for workspace "default"
2022-08-15T14:18:43.568+0100 [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2022-08-15T14:18:43.568+0100 [TRACE] statemgr.Filesystem: read snapshot with lineage "fef22b26-9eee-6416-dd98-9b658cb275de" serial 32
2022-08-15T14:18:43.568+0100 [TRACE] backend/local: populating backend.LocalRun for current working directory
2022-08-15T14:18:43.568+0100 [TRACE] Config.VerifyDependencySelections: provider registry.terraform.io/hashicorp/time has 0.8.0 to satisfy ""
2022-08-15T14:18:43.568+0100 [TRACE] Config.VerifyDependencySelections: provider registry.terraform.io/hashicorp/local has 2.2.3 to satisfy ""
2022-08-15T14:18:43.569+0100 [TRACE] terraform.NewContext: starting
2022-08-15T14:18:43.569+0100 [TRACE] terraform.NewContext: complete
2022-08-15T14:18:43.569+0100 [TRACE] backend/local: requesting interactive input, if necessary
2022-08-15T14:18:43.569+0100 [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/hashicorp/local"
2022-08-15T14:18:43.569+0100 [TRACE] terraform.contextPlugins: Initializing provider "registry.terraform.io/hashicorp/local" to read its schema
2022-08-15T14:18:43.569+0100 [DEBUG] created provider logger: level=trace
2022-08-15T14:18:43.569+0100 [INFO]  provider: configuring client automatic mTLS
2022-08-15T14:18:43.580+0100 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 args=[.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5]
2022-08-15T14:18:43.581+0100 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 pid=57181
2022-08-15T14:18:43.582+0100 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5
2022-08-15T14:18:43.595+0100 [INFO]  provider.terraform-provider-local_v2.2.3_x5: configuring server automatic mTLS: timestamp=2022-08-15T14:18:43.594+0100
2022-08-15T14:18:43.616+0100 [DEBUG] provider.terraform-provider-local_v2.2.3_x5: plugin address: address=/var/folders/x3/kc25vbrj7dvdm9rr8d1kzyvm0000gr/T/plugin442770744 network=unix timestamp=2022-08-15T14:18:43.616+0100
2022-08-15T14:18:43.616+0100 [DEBUG] provider: using plugin: version=5
2022-08-15T14:18:43.635+0100 [TRACE] GRPCProvider: GetProviderSchema
2022-08-15T14:18:43.635+0100 [TRACE] provider.stdio: waiting for stdio data
2022-08-15T14:18:43.635+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:490 @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 timestamp=2022-08-15T14:18:43.635+0100
2022-08-15T14:18:43.635+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_proto_version=5.2 tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:497 tf_provider_addr=provider @module=sdk.proto timestamp=2022-08-15T14:18:43.635+0100
2022-08-15T14:18:43.636+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Getting provider schema: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:70 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.635+0100
2022-08-15T14:18:43.636+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found resource type: tf_provider_addr=provider tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 tf_resource_type=local_file tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.636+0100
2022-08-15T14:18:43.636+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found resource type: @module=sdk.helper_schema tf_resource_type=local_sensitive_file tf_provider_addr=provider tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 timestamp=2022-08-15T14:18:43.636+0100
2022-08-15T14:18:43.636+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found data source type: @module=sdk.helper_schema tf_provider_addr=provider tf_rpc=GetProviderSchema tf_data_source_type=local_file tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:95 timestamp=2022-08-15T14:18:43.636+0100
2022-08-15T14:18:43.636+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found data source type: tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:95 tf_data_source_type=local_sensitive_file tf_provider_addr=provider @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.636+0100
2022-08-15T14:18:43.636+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: tf_proto_version=5.2 tf_provider_addr=provider @module=sdk.proto tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:503 timestamp=2022-08-15T14:18:43.636+0100
2022-08-15T14:18:43.636+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: tf_req_id=3947f420-6690-9e9a-5d0f-babc0ab26fd0 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:509 @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider timestamp=2022-08-15T14:18:43.636+0100
2022-08-15T14:18:43.637+0100 [TRACE] GRPCProvider: Close
2022-08-15T14:18:43.637+0100 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-08-15T14:18:43.637+0100 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 pid=57181
2022-08-15T14:18:43.637+0100 [DEBUG] provider: plugin exited
2022-08-15T14:18:43.637+0100 [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/hashicorp/time"
2022-08-15T14:18:43.637+0100 [TRACE] terraform.contextPlugins: Initializing provider "registry.terraform.io/hashicorp/time" to read its schema
2022-08-15T14:18:43.637+0100 [DEBUG] created provider logger: level=trace
2022-08-15T14:18:43.637+0100 [INFO]  provider: configuring client automatic mTLS
2022-08-15T14:18:43.641+0100 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 args=[.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5]
2022-08-15T14:18:43.642+0100 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 pid=57182
2022-08-15T14:18:43.642+0100 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5
2022-08-15T14:18:43.650+0100 [INFO]  provider.terraform-provider-time_v0.8.0_x5: configuring server automatic mTLS: timestamp=2022-08-15T14:18:43.650+0100
2022-08-15T14:18:43.658+0100 [DEBUG] provider: using plugin: version=5
2022-08-15T14:18:43.658+0100 [DEBUG] provider.terraform-provider-time_v0.8.0_x5: plugin address: address=/var/folders/x3/kc25vbrj7dvdm9rr8d1kzyvm0000gr/T/plugin2205981039 network=unix timestamp=2022-08-15T14:18:43.658+0100
2022-08-15T14:18:43.666+0100 [TRACE] GRPCProvider: GetProviderSchema
2022-08-15T14:18:43.667+0100 [TRACE] provider.stdio: waiting for stdio data
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_provider_addr=provider tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:491 @module=sdk.proto tf_proto_version=5.3 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 tf_rpc=GetProviderSchema @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Getting provider schema: tf_provider_addr=provider tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:70 @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_provider_addr=provider tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c tf_rpc=GetProviderSchema @module=sdk.helper_schema tf_resource_type=time_offset timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c tf_resource_type=time_rotating @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: tf_resource_type=time_sleep @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_provider_addr=provider tf_resource_type=time_static tf_rpc=GetProviderSchema @module=sdk.helper_schema tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c diagnostic_error_count=0 diagnostic_warning_count=0 tf_req_duration_ms=0 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 @module=sdk.proto timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.667+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:510 tf_proto_version=5.3 @module=sdk.proto tf_provider_addr=provider tf_req_id=836a4d10-4107-64cc-77e6-8a2ad92ad72c tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.667+0100
2022-08-15T14:18:43.668+0100 [TRACE] GRPCProvider: Close
2022-08-15T14:18:43.668+0100 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-08-15T14:18:43.668+0100 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 pid=57182
2022-08-15T14:18:43.668+0100 [DEBUG] provider: plugin exited
2022-08-15T14:18:43.668+0100 [TRACE] Context.Input: Prompting for provider arguments
2022-08-15T14:18:43.668+0100 [TRACE] Context.Input: Provider provider.time implied by resource block at main.tf:1,1-34
2022-08-15T14:18:43.668+0100 [TRACE] Context.Input: Provider provider.local implied by resource block at main.tf:11,1-29
2022-08-15T14:18:43.668+0100 [TRACE] Context.Input: Input for provider.time: map[string]cty.Value{}
2022-08-15T14:18:43.668+0100 [TRACE] Context.Input: Input for provider.local: map[string]cty.Value{}
2022-08-15T14:18:43.668+0100 [TRACE] backend/local: running validation operation
2022-08-15T14:18:43.668+0100 [DEBUG] Building and walking validate graph
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.ConfigTransformer
2022-08-15T14:18:43.669+0100 [TRACE] ConfigTransformer: Starting for path:
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  local_file.test - *terraform.NodeValidatableResource
  time_rotating.rotate - *terraform.NodeValidatableResource
  ------
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.LocalTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.OutputTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.StateTransformer
2022-08-15T14:18:43.669+0100 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2022-08-15T14:18:43.669+0100 [TRACE] AttachResourceConfigTransformer: attaching to "local_file.test" (*terraform.NodeValidatableResource) config from main.tf:11,1-29
2022-08-15T14:18:43.669+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to local_file.test
2022-08-15T14:18:43.669+0100 [TRACE] AttachResourceConfigTransformer: attaching to "time_rotating.rotate" (*terraform.NodeValidatableResource) config from main.tf:1,1-34
2022-08-15T14:18:43.669+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to time_rotating.rotate
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2022-08-15T14:18:43.669+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2022-08-15T14:18:43.669+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  local_file.test - *terraform.NodeValidatableResource
  time_rotating.rotate - *terraform.NodeValidatableResource
  ------
2022-08-15T14:18:43.669+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2022-08-15T14:18:43.669+0100 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/time"], implied first by time_rotating.rotate
2022-08-15T14:18:43.669+0100 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/local"], implied first by local_file.test
2022-08-15T14:18:43.669+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
  local_file.test - *terraform.NodeValidatableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeValidatableResource
  ------
2022-08-15T14:18:43.669+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2022-08-15T14:18:43.669+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/time"] serving time_rotating.rotate
2022-08-15T14:18:43.669+0100 [DEBUG] ProviderTransformer: "time_rotating.rotate" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.669+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/local"] serving local_file.test
2022-08-15T14:18:43.669+0100 [DEBUG] ProviderTransformer: "local_file.test" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.669+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  local_file.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.669+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2022-08-15T14:18:43.669+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  local_file.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2022-08-15T14:18:43.669+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to time_rotating.rotate
2022-08-15T14:18:43.669+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to local_file.test
2022-08-15T14:18:43.669+0100 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.669+0100 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2022-08-15T14:18:43.669+0100 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2022-08-15T14:18:43.669+0100 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2022-08-15T14:18:43.670+0100 [DEBUG] ReferenceTransformer: "time_rotating.rotate" references: []
2022-08-15T14:18:43.670+0100 [INFO]  ReferenceTransformer: reference not found: "path.module"
2022-08-15T14:18:43.670+0100 [DEBUG] ReferenceTransformer: "local_file.test" references: [time_rotating.rotate]
2022-08-15T14:18:43.670+0100 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/time\"]" references: []
2022-08-15T14:18:43.670+0100 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/local\"]" references: []
2022-08-15T14:18:43.670+0100 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
  local_file.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeValidatableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.670+0100 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2022-08-15T14:18:43.670+0100 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2022-08-15T14:18:43.670+0100 [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2022-08-15T14:18:43.670+0100 [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2022-08-15T14:18:43.670+0100 [TRACE] Executing graph transform *terraform.TargetsTransformer
2022-08-15T14:18:43.670+0100 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2022-08-15T14:18:43.670+0100 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2022-08-15T14:18:43.670+0100 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2022-08-15T14:18:43.670+0100 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2022-08-15T14:18:43.670+0100 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  local_file.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeValidatableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    local_file.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeValidatableResource
  time_rotating.rotate - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.670+0100 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2022-08-15T14:18:43.670+0100 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  local_file.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeValidatableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    local_file.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeValidatableResource
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
  time_rotating.rotate - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.670+0100 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2022-08-15T14:18:43.670+0100 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  local_file.test - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeValidatableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    local_file.test - *terraform.NodeValidatableResource
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    time_rotating.rotate - *terraform.NodeValidatableResource
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
  time_rotating.rotate - *terraform.NodeValidatableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.670+0100 [DEBUG] Starting graph walk: walkValidate
2022-08-15T14:18:43.671+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/local\"]": starting visit (*terraform.NodeApplyableProvider)
2022-08-15T14:18:43.671+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"]": starting visit (*terraform.NodeApplyableProvider)
2022-08-15T14:18:43.672+0100 [DEBUG] created provider logger: level=trace
2022-08-15T14:18:43.672+0100 [INFO]  provider: configuring client automatic mTLS
2022-08-15T14:18:43.675+0100 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 args=[.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5]
2022-08-15T14:18:43.677+0100 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 pid=57183
2022-08-15T14:18:43.677+0100 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5
2022-08-15T14:18:43.682+0100 [INFO]  provider.terraform-provider-local_v2.2.3_x5: configuring server automatic mTLS: timestamp=2022-08-15T14:18:43.682+0100
2022-08-15T14:18:43.704+0100 [DEBUG] provider.terraform-provider-local_v2.2.3_x5: plugin address: address=/var/folders/x3/kc25vbrj7dvdm9rr8d1kzyvm0000gr/T/plugin302203090 network=unix timestamp=2022-08-15T14:18:43.704+0100
2022-08-15T14:18:43.704+0100 [DEBUG] provider: using plugin: version=5
2022-08-15T14:18:43.720+0100 [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/hashicorp/local\"]" provider for provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.720+0100 [TRACE] provider.stdio: waiting for stdio data
2022-08-15T14:18:43.720+0100 [DEBUG] created provider logger: level=trace
2022-08-15T14:18:43.720+0100 [INFO]  provider: configuring client automatic mTLS
2022-08-15T14:18:43.723+0100 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 args=[.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5]
2022-08-15T14:18:43.725+0100 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 pid=57184
2022-08-15T14:18:43.725+0100 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5
2022-08-15T14:18:43.729+0100 [INFO]  provider.terraform-provider-time_v0.8.0_x5: configuring server automatic mTLS: timestamp=2022-08-15T14:18:43.729+0100
2022-08-15T14:18:43.737+0100 [DEBUG] provider: using plugin: version=5
2022-08-15T14:18:43.737+0100 [DEBUG] provider.terraform-provider-time_v0.8.0_x5: plugin address: address=/var/folders/x3/kc25vbrj7dvdm9rr8d1kzyvm0000gr/T/plugin2928657634 network=unix timestamp=2022-08-15T14:18:43.737+0100
2022-08-15T14:18:43.746+0100 [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/hashicorp/time\"]" provider for provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.746+0100 [TRACE] provider.stdio: waiting for stdio data
2022-08-15T14:18:43.746+0100 [TRACE] NodeApplyableProvider: validating configuration for provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.746+0100 [TRACE] NodeApplyableProvider: validating configuration for provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.746+0100 [TRACE] buildProviderConfig for provider["registry.terraform.io/hashicorp/local"]: no configuration at all
2022-08-15T14:18:43.746+0100 [TRACE] buildProviderConfig for provider["registry.terraform.io/hashicorp/time"]: no configuration at all
2022-08-15T14:18:43.746+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"]": visit complete
2022-08-15T14:18:43.746+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/local\"]": visit complete
2022-08-15T14:18:43.746+0100 [TRACE] vertex "time_rotating.rotate": starting visit (*terraform.NodeValidatableResource)
2022-08-15T14:18:43.748+0100 [TRACE] GRPCProvider: ValidateResourceConfig
2022-08-15T14:18:43.748+0100 [TRACE] GRPCProvider: GetProviderSchema
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_proto_version=5.3 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:491 tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa @module=sdk.proto tf_provider_addr=provider timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: tf_proto_version=5.3 tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa tf_rpc=GetProviderSchema @module=sdk.proto tf_provider_addr=provider @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Getting provider schema: tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:70 @module=sdk.helper_schema tf_provider_addr=provider timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: tf_provider_addr=provider @module=sdk.helper_schema tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa tf_resource_type=time_static tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_resource_type=time_offset tf_rpc=GetProviderSchema @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @module=sdk.helper_schema tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_provider_addr=provider tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @module=sdk.helper_schema tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa tf_rpc=GetProviderSchema tf_resource_type=time_sleep @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_provider_addr=provider timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: diagnostic_error_count=0 tf_proto_version=5.3 tf_provider_addr=provider @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 diagnostic_warning_count=0 tf_req_duration_ms=0 tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa tf_rpc=GetProviderSchema @module=sdk.proto timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.748+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:510 tf_provider_addr=provider tf_rpc=GetProviderSchema tf_req_id=4f6b6cdd-d9c2-f2e4-0819-611d0dadaafa @module=sdk.proto tf_proto_version=5.3 timestamp=2022-08-15T14:18:43.748+0100
2022-08-15T14:18:43.749+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_proto_version=5.3 tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:679 @module=sdk.proto tf_provider_addr=provider tf_req_id=7c8574ec-4892-5308-062f-c69e654070c6 tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.749+0100
2022-08-15T14:18:43.749+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Skipping protocol data file writing because no data directory is set. Use the TF_LOG_SDK_PROTO_DATA_DIR environment variable to enable this functionality.: tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/internal/logging/protocol_data.go:38 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=7c8574ec-4892-5308-062f-c69e654070c6 tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.749+0100
2022-08-15T14:18:43.749+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: @module=sdk.proto tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 tf_provider_addr=provider tf_req_id=7c8574ec-4892-5308-062f-c69e654070c6 tf_resource_type=time_rotating tf_proto_version=5.3 timestamp=2022-08-15T14:18:43.749+0100
2022-08-15T14:18:43.749+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:245 tf_provider_addr=provider tf_req_id=7c8574ec-4892-5308-062f-c69e654070c6 tf_resource_type=time_rotating @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.749+0100
2022-08-15T14:18:43.750+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: tf_resource_type=time_rotating tf_req_id=7c8574ec-4892-5308-062f-c69e654070c6 tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:247 @module=sdk.helper_schema tf_provider_addr=provider timestamp=2022-08-15T14:18:43.750+0100
2022-08-15T14:18:43.750+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: tf_req_duration_ms=0 tf_resource_type=time_rotating tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 @module=sdk.proto diagnostic_error_count=0 tf_provider_addr=provider tf_req_id=7c8574ec-4892-5308-062f-c69e654070c6 diagnostic_warning_count=0 tf_proto_version=5.3 timestamp=2022-08-15T14:18:43.750+0100
2022-08-15T14:18:43.750+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:699 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=7c8574ec-4892-5308-062f-c69e654070c6 tf_resource_type=time_rotating tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.750+0100
2022-08-15T14:18:43.750+0100 [TRACE] vertex "time_rotating.rotate": visit complete
2022-08-15T14:18:43.750+0100 [TRACE] vertex "local_file.test": starting visit (*terraform.NodeValidatableResource)
2022-08-15T14:18:43.750+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2022-08-15T14:18:43.750+0100 [TRACE] GRPCProvider: Close
2022-08-15T14:18:43.750+0100 [TRACE] GRPCProvider: ValidateResourceConfig
2022-08-15T14:18:43.750+0100 [TRACE] GRPCProvider: GetProviderSchema
2022-08-15T14:18:43.751+0100 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:490 tf_provider_addr=provider tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 tf_rpc=GetProviderSchema @module=sdk.proto tf_proto_version=5.2 timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_rpc=GetProviderSchema tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:497 @module=sdk.proto timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Getting provider schema: tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:70 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found resource type: tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema tf_provider_addr=provider tf_resource_type=local_sensitive_file tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found resource type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 tf_resource_type=local_file tf_provider_addr=provider tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found data source type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:95 @module=sdk.helper_schema tf_provider_addr=provider tf_data_source_type=local_sensitive_file tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found data source type: tf_rpc=GetProviderSchema @module=sdk.helper_schema tf_data_source_type=local_file tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:95 tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:503 tf_proto_version=5.2 tf_rpc=GetProviderSchema @module=sdk.proto tf_provider_addr=provider tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:509 tf_provider_addr=provider tf_rpc=GetProviderSchema @module=sdk.proto tf_proto_version=5.2 tf_req_id=c3dccbed-55b3-5667-c495-6f785ff49ce7 timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: tf_req_id=e2a9b80a-c39d-eefc-743f-a714b334fc58 @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_resource_type=local_file tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:678 timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Skipping protocol data file writing because no data directory is set. Use the TF_LOG_SDK_PROTO_DATA_DIR environment variable to enable this functionality.: tf_resource_type=local_file @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=e2a9b80a-c39d-eefc-743f-a714b334fc58 @caller=github.com/hashicorp/[email protected]/internal/logging/protocol_data.go:38 tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_rpc=ValidateResourceTypeConfig tf_provider_addr=provider tf_req_id=e2a9b80a-c39d-eefc-743f-a714b334fc58 tf_resource_type=local_file @module=sdk.proto tf_proto_version=5.2 @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:686 timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.751+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_provider_addr=provider tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:245 @module=sdk.helper_schema tf_req_id=e2a9b80a-c39d-eefc-743f-a714b334fc58 tf_resource_type=local_file timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.752+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_resource_type=local_file tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:247 tf_req_id=e2a9b80a-c39d-eefc-743f-a714b334fc58 timestamp=2022-08-15T14:18:43.751+0100
2022-08-15T14:18:43.752+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: tf_resource_type=local_file tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:692 @module=sdk.proto tf_provider_addr=provider tf_req_id=e2a9b80a-c39d-eefc-743f-a714b334fc58 tf_proto_version=5.2 timestamp=2022-08-15T14:18:43.752+0100
2022-08-15T14:18:43.752+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:698 @module=sdk.proto tf_proto_version=5.2 tf_resource_type=local_file tf_provider_addr=provider tf_req_id=e2a9b80a-c39d-eefc-743f-a714b334fc58 tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.752+0100
2022-08-15T14:18:43.752+0100 [TRACE] vertex "local_file.test": visit complete
2022-08-15T14:18:43.752+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/local\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2022-08-15T14:18:43.752+0100 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 pid=57184
2022-08-15T14:18:43.752+0100 [DEBUG] provider: plugin exited
2022-08-15T14:18:43.752+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"] (close)": visit complete
2022-08-15T14:18:43.752+0100 [TRACE] GRPCProvider: Close
2022-08-15T14:18:43.753+0100 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-08-15T14:18:43.753+0100 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 pid=57183
2022-08-15T14:18:43.753+0100 [DEBUG] provider: plugin exited
2022-08-15T14:18:43.753+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/local\"] (close)": visit complete
2022-08-15T14:18:43.753+0100 [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2022-08-15T14:18:43.753+0100 [TRACE] vertex "root": visit complete
2022-08-15T14:18:43.753+0100 [INFO]  backend/local: apply calling Plan
2022-08-15T14:18:43.753+0100 [DEBUG] Building and walking plan graph for NormalMode
2022-08-15T14:18:43.753+0100 [TRACE] Executing graph transform *terraform.ConfigTransformer
2022-08-15T14:18:43.753+0100 [TRACE] ConfigTransformer: Starting for path:
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  ------
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.LocalTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.OutputTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.StateTransformer
2022-08-15T14:18:43.754+0100 [TRACE] StateTransformer: creating nodes for deposed instance objects only
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2022-08-15T14:18:43.754+0100 [TRACE] AttachResourceConfigTransformer: attaching to "local_file.test (expand)" (*terraform.nodeExpandPlannableResource) config from main.tf:11,1-29
2022-08-15T14:18:43.754+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to local_file.test (expand)
2022-08-15T14:18:43.754+0100 [TRACE] AttachResourceConfigTransformer: attaching to "time_rotating.rotate (expand)" (*terraform.nodeExpandPlannableResource) config from main.tf:1,1-34
2022-08-15T14:18:43.754+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to time_rotating.rotate (expand)
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2022-08-15T14:18:43.754+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2022-08-15T14:18:43.754+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  ------
2022-08-15T14:18:43.754+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2022-08-15T14:18:43.754+0100 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/local"], implied first by local_file.test (expand)
2022-08-15T14:18:43.754+0100 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/time"], implied first by time_rotating.rotate (expand)
2022-08-15T14:18:43.754+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  ------
2022-08-15T14:18:43.754+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2022-08-15T14:18:43.754+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/local"] serving local_file.test (expand)
2022-08-15T14:18:43.754+0100 [DEBUG] ProviderTransformer: "local_file.test (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.754+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/time"] serving time_rotating.rotate (expand)
2022-08-15T14:18:43.754+0100 [DEBUG] ProviderTransformer: "time_rotating.rotate (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.754+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.754+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2022-08-15T14:18:43.754+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2022-08-15T14:18:43.754+0100 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.754+0100 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.754+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to local_file.test (expand)
2022-08-15T14:18:43.754+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to time_rotating.rotate (expand)
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2022-08-15T14:18:43.754+0100 [DEBUG] ReferenceTransformer: "time_rotating.rotate (expand)" references: []
2022-08-15T14:18:43.754+0100 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/local\"]" references: []
2022-08-15T14:18:43.754+0100 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/time\"]" references: []
2022-08-15T14:18:43.754+0100 [INFO]  ReferenceTransformer: reference not found: "path.module"
2022-08-15T14:18:43.754+0100 [DEBUG] ReferenceTransformer: "local_file.test (expand)" references: [time_rotating.rotate (expand)]
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.ReferenceTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2022-08-15T14:18:43.754+0100 [TRACE] AttachDependenciesTransformer: local_file.test depends on [time_rotating.rotate]
2022-08-15T14:18:43.754+0100 [TRACE] AttachDependenciesTransformer: time_rotating.rotate depends on []
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.TargetsTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2022-08-15T14:18:43.754+0100 [TRACE] ForcedCBDTransformer: "local_file.test (expand)" (*terraform.nodeExpandPlannableResource) has no CBD descendent, so skipping
2022-08-15T14:18:43.754+0100 [TRACE] ForcedCBDTransformer: "time_rotating.rotate (expand)" (*terraform.nodeExpandPlannableResource) has no CBD descendent, so skipping
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2022-08-15T14:18:43.754+0100 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    local_file.test (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.754+0100 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2022-08-15T14:18:43.755+0100 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    local_file.test (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.755+0100 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2022-08-15T14:18:43.755+0100 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    local_file.test (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/local"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
  time_rotating.rotate (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.755+0100 [DEBUG] Starting graph walk: walkPlan
2022-08-15T14:18:43.755+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/local\"]": starting visit (*terraform.NodeApplyableProvider)
2022-08-15T14:18:43.755+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"]": starting visit (*terraform.NodeApplyableProvider)
2022-08-15T14:18:43.755+0100 [DEBUG] created provider logger: level=trace
2022-08-15T14:18:43.755+0100 [INFO]  provider: configuring client automatic mTLS
2022-08-15T14:18:43.759+0100 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 args=[.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5]
2022-08-15T14:18:43.760+0100 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 pid=57185
2022-08-15T14:18:43.760+0100 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5
2022-08-15T14:18:43.765+0100 [INFO]  provider.terraform-provider-local_v2.2.3_x5: configuring server automatic mTLS: timestamp=2022-08-15T14:18:43.765+0100
2022-08-15T14:18:43.786+0100 [DEBUG] provider: using plugin: version=5
2022-08-15T14:18:43.786+0100 [DEBUG] provider.terraform-provider-local_v2.2.3_x5: plugin address: address=/var/folders/x3/kc25vbrj7dvdm9rr8d1kzyvm0000gr/T/plugin797427524 network=unix timestamp=2022-08-15T14:18:43.786+0100
2022-08-15T14:18:43.802+0100 [TRACE] provider.stdio: waiting for stdio data
2022-08-15T14:18:43.802+0100 [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/hashicorp/local\"]" provider for provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.802+0100 [TRACE] NodeApplyableProvider: configuring provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.802+0100 [DEBUG] created provider logger: level=trace
2022-08-15T14:18:43.802+0100 [INFO]  provider: configuring client automatic mTLS
2022-08-15T14:18:43.805+0100 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 args=[.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5]
2022-08-15T14:18:43.807+0100 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 pid=57186
2022-08-15T14:18:43.807+0100 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5
2022-08-15T14:18:43.811+0100 [INFO]  provider.terraform-provider-time_v0.8.0_x5: configuring server automatic mTLS: timestamp=2022-08-15T14:18:43.811+0100
2022-08-15T14:18:43.819+0100 [DEBUG] provider: using plugin: version=5
2022-08-15T14:18:43.819+0100 [DEBUG] provider.terraform-provider-time_v0.8.0_x5: plugin address: address=/var/folders/x3/kc25vbrj7dvdm9rr8d1kzyvm0000gr/T/plugin821398718 network=unix timestamp=2022-08-15T14:18:43.819+0100
2022-08-15T14:18:43.827+0100 [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/hashicorp/time\"]" provider for provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.827+0100 [TRACE] provider.stdio: waiting for stdio data
2022-08-15T14:18:43.827+0100 [TRACE] NodeApplyableProvider: configuring provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.827+0100 [TRACE] buildProviderConfig for provider["registry.terraform.io/hashicorp/local"]: no configuration at all
2022-08-15T14:18:43.827+0100 [TRACE] GRPCProvider: GetProviderSchema
2022-08-15T14:18:43.827+0100 [TRACE] buildProviderConfig for provider["registry.terraform.io/hashicorp/time"]: no configuration at all
2022-08-15T14:18:43.827+0100 [TRACE] GRPCProvider: GetProviderSchema
2022-08-15T14:18:43.827+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:491 tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=GetProviderSchema @module=sdk.proto tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.827+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.827+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Getting provider schema: tf_provider_addr=provider tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:70 @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.827+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @module=sdk.helper_schema tf_provider_addr=provider tf_resource_type=time_offset @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.827+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_resource_type=time_rotating @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.827+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: tf_resource_type=time_sleep @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.827+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_provider_addr=provider tf_resource_type=time_static @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.827+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: diagnostic_error_count=0 tf_proto_version=5.3 diagnostic_warning_count=0 tf_provider_addr=provider tf_req_duration_ms=0 tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 @module=sdk.proto tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: tf_provider_addr=provider @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:510 @module=sdk.proto tf_proto_version=5.3 tf_req_id=46ee8325-36ec-1230-5a2e-71302de084d9 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: tf_proto_version=5.2 tf_provider_addr=provider tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:490 @module=sdk.proto tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_proto_version=5.2 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:497 @module=sdk.proto tf_provider_addr=provider tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Getting provider schema: tf_provider_addr=provider tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:70 @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found resource type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_resource_type=local_file @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found resource type: tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 tf_resource_type=local_sensitive_file tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found data source type: @module=sdk.helper_schema tf_data_source_type=local_file tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:95 tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Found data source type: @module=sdk.helper_schema tf_data_source_type=local_sensitive_file tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:95 tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:503 @module=sdk.proto tf_provider_addr=provider tf_rpc=GetProviderSchema tf_proto_version=5.2 tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:509 tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=c9137f12-185a-7317-c892-2fd225145b12 tf_rpc=GetProviderSchema @module=sdk.proto timestamp=2022-08-15T14:18:43.827+0100
2022-08-15T14:18:43.828+0100 [TRACE] GRPCProvider: ValidateProviderConfig
2022-08-15T14:18:43.828+0100 [TRACE] GRPCProvider: ValidateProviderConfig
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:517 tf_req_id=8d19286c-4fdd-0848-5049-b7dfb7eb9a39 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Skipping protocol data file writing because no data directory is set. Use the TF_LOG_SDK_PROTO_DATA_DIR environment variable to enable this functionality.: @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=8d19286c-4fdd-0848-5049-b7dfb7eb9a39 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/[email protected]/internal/logging/protocol_data.go:38 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_req_id=8d19286c-4fdd-0848-5049-b7dfb7eb9a39 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:525 tf_proto_version=5.2 tf_provider_addr=provider @module=sdk.proto timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Preparing provider configuration: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=8d19286c-4fdd-0848-5049-b7dfb7eb9a39 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:128 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_req_id=8d19286c-4fdd-0848-5049-b7dfb7eb9a39 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:216 @module=sdk.helper_schema tf_provider_addr=provider timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_provider_addr=provider tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:518 @module=sdk.proto tf_proto_version=5.3 tf_req_id=787eff4b-b830-164e-4c19-dcef0a583cdf timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Skipping protocol data file writing because no data directory is set. Use the TF_LOG_SDK_PROTO_DATA_DIR environment variable to enable this functionality.: @caller=github.com/hashicorp/[email protected]/internal/logging/protocol_data.go:38 tf_req_id=787eff4b-b830-164e-4c19-dcef0a583cdf tf_rpc=PrepareProviderConfig @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: @module=sdk.proto tf_proto_version=5.3 tf_req_id=787eff4b-b830-164e-4c19-dcef0a583cdf @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 tf_provider_addr=provider tf_rpc=PrepareProviderConfig timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Preparing provider configuration: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:128 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=787eff4b-b830-164e-4c19-dcef0a583cdf tf_rpc=PrepareProviderConfig timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:216 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=787eff4b-b830-164e-4c19-dcef0a583cdf tf_rpc=PrepareProviderConfig timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=787eff4b-b830-164e-4c19-dcef0a583cdf tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:218 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: tf_provider_addr=provider tf_req_duration_ms=0 @module=sdk.proto diagnostic_error_count=0 diagnostic_warning_count=0 @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 tf_proto_version=5.3 tf_req_id=787eff4b-b830-164e-4c19-dcef0a583cdf tf_rpc=PrepareProviderConfig timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:218 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=8d19286c-4fdd-0848-5049-b7dfb7eb9a39 tf_rpc=PrepareProviderConfig timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:539 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=787eff4b-b830-164e-4c19-dcef0a583cdf tf_rpc=PrepareProviderConfig timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: tf_proto_version=5.2 tf_req_id=8d19286c-4fdd-0848-5049-b7dfb7eb9a39 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:531 @module=sdk.proto tf_provider_addr=provider timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:538 @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_rpc=PrepareProviderConfig tf_req_id=8d19286c-4fdd-0848-5049-b7dfb7eb9a39 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] GRPCProvider: ConfigureProvider
2022-08-15T14:18:43.828+0100 [TRACE] GRPCProvider: ConfigureProvider
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=0b71805d-a081-a762-e6ea-5f2e805c23e8 tf_rpc=Configure @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:547 @module=sdk.proto timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=0b71805d-a081-a762-e6ea-5f2e805c23e8 tf_rpc=Configure timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: tf_req_id=0b71805d-a081-a762-e6ea-5f2e805c23e8 tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:556 @module=sdk.helper_schema tf_provider_addr=provider timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: tf_provider_addr=provider tf_rpc=Configure @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:546 @module=sdk.proto tf_proto_version=5.2 tf_req_id=14f6e581-59bd-b46f-6155-ca622475d954 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_proto_version=5.2 tf_provider_addr=provider @module=sdk.proto tf_req_id=14f6e581-59bd-b46f-6155-ca622475d954 tf_rpc=Configure @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:554 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:558 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=0b71805d-a081-a762-e6ea-5f2e805c23e8 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: @module=sdk.proto diagnostic_error_count=0 diagnostic_warning_count=0 tf_proto_version=5.3 tf_req_id=0b71805d-a081-a762-e6ea-5f2e805c23e8 tf_rpc=Configure @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 tf_provider_addr=provider tf_req_duration_ms=0 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: tf_req_id=0b71805d-a081-a762-e6ea-5f2e805c23e8 @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:567 tf_proto_version=5.3 tf_provider_addr=provider @module=sdk.proto tf_rpc=Configure timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=14f6e581-59bd-b46f-6155-ca622475d954 tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:556 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: tf_provider_addr=provider tf_req_id=14f6e581-59bd-b46f-6155-ca622475d954 tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:558 @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:560 @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=14f6e581-59bd-b46f-6155-ca622475d954 tf_rpc=Configure timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"]": visit complete
2022-08-15T14:18:43.828+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/local\"]": visit complete
2022-08-15T14:18:43.828+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:566 @module=sdk.proto tf_rpc=Configure tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=14f6e581-59bd-b46f-6155-ca622475d954 timestamp=2022-08-15T14:18:43.828+0100
2022-08-15T14:18:43.828+0100 [TRACE] vertex "time_rotating.rotate (expand)": starting visit (*terraform.nodeExpandPlannableResource)
2022-08-15T14:18:43.828+0100 [TRACE] vertex "time_rotating.rotate (expand)": expanding dynamic subgraph
2022-08-15T14:18:43.828+0100 [TRACE] vertex "time_rotating.rotate (expand)": entering dynamic subgraph
2022-08-15T14:18:43.828+0100 [TRACE] vertex "time_rotating.rotate": starting visit (*terraform.NodePlannableResource)
2022-08-15T14:18:43.829+0100 [TRACE] vertex "time_rotating.rotate": expanding dynamic subgraph
2022-08-15T14:18:43.829+0100 [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2022-08-15T14:18:43.829+0100 [TRACE] ResourceCountTransformer: adding time_rotating.rotate as *terraform.NodePlannableResourceInstance
2022-08-15T14:18:43.829+0100 [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
  time_rotating.rotate - *terraform.NodePlannableResourceInstance
  ------
2022-08-15T14:18:43.829+0100 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceCountTransformer
2022-08-15T14:18:43.829+0100 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceCountTransformer (no changes)
2022-08-15T14:18:43.829+0100 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2022-08-15T14:18:43.829+0100 [TRACE] NodeAbstractResourceInstance.AttachResourceState for time_rotating.rotate
2022-08-15T14:18:43.829+0100 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2022-08-15T14:18:43.829+0100 [TRACE] Executing graph transform *terraform.TargetsTransformer
2022-08-15T14:18:43.829+0100 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2022-08-15T14:18:43.829+0100 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2022-08-15T14:18:43.829+0100 [DEBUG] ReferenceTransformer: "time_rotating.rotate" references: []
2022-08-15T14:18:43.829+0100 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2022-08-15T14:18:43.829+0100 [TRACE] Executing graph transform *terraform.RootTransformer
2022-08-15T14:18:43.829+0100 [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2022-08-15T14:18:43.829+0100 [TRACE] vertex "time_rotating.rotate": entering dynamic subgraph
2022-08-15T14:18:43.829+0100 [TRACE] vertex "time_rotating.rotate": starting visit (*terraform.NodePlannableResourceInstance)
2022-08-15T14:18:43.830+0100 [TRACE] readResourceInstanceState: reading state for time_rotating.rotate
2022-08-15T14:18:43.830+0100 [TRACE] upgradeResourceState: schema version of time_rotating.rotate is still 0; calling provider "time" for any other minor fixups
2022-08-15T14:18:43.830+0100 [TRACE] GRPCProvider: UpgradeResourceState
2022-08-15T14:18:43.830+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=780c0d9e-1290-d6f6-50e1-e18afba4c790 @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:708 tf_resource_type=time_rotating tf_rpc=UpgradeResourceState timestamp=2022-08-15T14:18:43.830+0100
2022-08-15T14:18:43.830+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: tf_proto_version=5.3 tf_provider_addr=provider tf_resource_type=time_rotating @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_req_id=780c0d9e-1290-d6f6-50e1-e18afba4c790 tf_rpc=UpgradeResourceState timestamp=2022-08-15T14:18:43.830+0100
2022-08-15T14:18:43.830+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Upgrading JSON state: tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:323 tf_resource_type=time_rotating tf_rpc=UpgradeResourceState @module=sdk.helper_schema tf_req_id=780c0d9e-1290-d6f6-50e1-e18afba4c790 timestamp=2022-08-15T14:18:43.830+0100
2022-08-15T14:18:43.831+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: tf_proto_version=5.3 tf_req_id=780c0d9e-1290-d6f6-50e1-e18afba4c790 tf_rpc=UpgradeResourceState @module=sdk.proto diagnostic_warning_count=0 tf_req_duration_ms=0 tf_resource_type=time_rotating @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 diagnostic_error_count=0 tf_provider_addr=provider timestamp=2022-08-15T14:18:43.830+0100
2022-08-15T14:18:43.831+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:728 @module=sdk.proto tf_provider_addr=provider tf_req_id=780c0d9e-1290-d6f6-50e1-e18afba4c790 tf_resource_type=time_rotating tf_rpc=UpgradeResourceState tf_proto_version=5.3 timestamp=2022-08-15T14:18:43.831+0100
2022-08-15T14:18:43.831+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to prevRunState for time_rotating.rotate
2022-08-15T14:18:43.831+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for time_rotating.rotate
2022-08-15T14:18:43.831+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for time_rotating.rotate
2022-08-15T14:18:43.831+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for time_rotating.rotate
2022-08-15T14:18:43.832+0100 [TRACE] NodeAbstractResourceInstance.refresh for time_rotating.rotate
2022-08-15T14:18:43.832+0100 [TRACE] GRPCProvider: ReadResource
2022-08-15T14:18:43.832+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:737 tf_rpc=ReadResource @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=7126bd7b-d088-10e1-ded9-3d17daf32dd7 tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.832+0100
2022-08-15T14:18:43.832+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: tf_provider_addr=provider tf_rpc=ReadResource @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_req_id=7126bd7b-d088-10e1-ded9-3d17daf32dd7 tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.832+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: @module=sdk.helper_schema tf_resource_type=time_rotating @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/resource.go:1014 tf_provider_addr=provider tf_req_id=7126bd7b-d088-10e1-ded9-3d17daf32dd7 tf_rpc=ReadResource timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [INFO]  provider.terraform-provider-time_v0.8.0_x5: 2022/08/15 14:18:43 [INFO] Expiration timestamp (2022-08-15T13:18:39Z) is after current timestamp (2022-08-15T13:18:43Z), removing from state: timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: tf_provider_addr=provider tf_req_id=7126bd7b-d088-10e1-ded9-3d17daf32dd7 tf_resource_type=time_rotating tf_rpc=ReadResource @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/resource.go:1016 @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: tf_rpc=ReadResource @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 diagnostic_error_count=0 diagnostic_warning_count=0 tf_req_id=7126bd7b-d088-10e1-ded9-3d17daf32dd7 tf_req_duration_ms=0 tf_resource_type=time_rotating @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @module=sdk.proto tf_provider_addr=provider tf_req_id=7126bd7b-d088-10e1-ded9-3d17daf32dd7 tf_resource_type=time_rotating tf_rpc=ReadResource @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:759 tf_proto_version=5.3 timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [WARN]  Provider "registry.terraform.io/hashicorp/time" produced an unexpected new value for time_rotating.rotate during refresh.
      - Root resource was present, but now absent
2022-08-15T14:18:43.833+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for time_rotating.rotate
2022-08-15T14:18:43.833+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for time_rotating.rotate
2022-08-15T14:18:43.833+0100 [TRACE] Re-validating config for "time_rotating.rotate"
2022-08-15T14:18:43.833+0100 [TRACE] GRPCProvider: ValidateResourceConfig
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: @module=sdk.proto tf_req_id=bf160970-7d96-d179-e93d-1ada5dfe8bf3 tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:679 tf_proto_version=5.3 tf_provider_addr=provider tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_resource_type=time_rotating @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 tf_req_id=bf160970-7d96-d179-e93d-1ada5dfe8bf3 tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: tf_provider_addr=provider tf_req_id=bf160970-7d96-d179-e93d-1ada5dfe8bf3 @module=sdk.helper_schema tf_resource_type=time_rotating tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:245 timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: tf_req_id=bf160970-7d96-d179-e93d-1ada5dfe8bf3 tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:247 @module=sdk.helper_schema tf_provider_addr=provider tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: tf_req_duration_ms=0 tf_resource_type=time_rotating diagnostic_error_count=0 diagnostic_warning_count=0 tf_proto_version=5.3 tf_provider_addr=provider @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 @module=sdk.proto tf_req_id=bf160970-7d96-d179-e93d-1ada5dfe8bf3 tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: tf_resource_type=time_rotating @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:699 tf_provider_addr=provider tf_rpc=ValidateResourceTypeConfig @module=sdk.proto tf_proto_version=5.3 tf_req_id=bf160970-7d96-d179-e93d-1ada5dfe8bf3 timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] GRPCProvider: PlanResourceChange
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_proto_version=5.3 tf_req_id=1ba2c25a-8154-b1c7-8fcc-1d53484c8ae3 tf_rpc=PlanResourceChange @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:768 @module=sdk.proto tf_provider_addr=provider tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.833+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: tf_resource_type=time_rotating tf_rpc=PlanResourceChange @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_req_id=1ba2c25a-8154-b1c7-8fcc-1d53484c8ae3 tf_proto_version=5.3 tf_provider_addr=provider timestamp=2022-08-15T14:18:43.833+0100
2022-08-15T14:18:43.834+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/schema.go:697 tf_provider_addr=provider tf_req_id=1ba2c25a-8154-b1c7-8fcc-1d53484c8ae3 @module=sdk.helper_schema tf_resource_type=time_rotating tf_rpc=PlanResourceChange timestamp=2022-08-15T14:18:43.834+0100
2022-08-15T14:18:43.834+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: @module=sdk.helper_schema tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/schema.go:699 tf_req_id=1ba2c25a-8154-b1c7-8fcc-1d53484c8ae3 tf_resource_type=time_rotating tf_rpc=PlanResourceChange timestamp=2022-08-15T14:18:43.834+0100
2022-08-15T14:18:43.834+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 @module=sdk.proto diagnostic_error_count=0 diagnostic_warning_count=0 tf_proto_version=5.3 tf_provider_addr=provider tf_req_duration_ms=0 tf_req_id=1ba2c25a-8154-b1c7-8fcc-1d53484c8ae3 tf_resource_type=time_rotating tf_rpc=PlanResourceChange timestamp=2022-08-15T14:18:43.834+0100
2022-08-15T14:18:43.834+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:792 @module=sdk.proto tf_provider_addr=provider tf_req_id=1ba2c25a-8154-b1c7-8fcc-1d53484c8ae3 tf_proto_version=5.3 tf_resource_type=time_rotating tf_rpc=PlanResourceChange timestamp=2022-08-15T14:18:43.834+0100
2022-08-15T14:18:43.834+0100 [TRACE] writeChange: recorded Create change for time_rotating.rotate
2022-08-15T14:18:43.834+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for time_rotating.rotate
2022-08-15T14:18:43.834+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for time_rotating.rotate
2022-08-15T14:18:43.834+0100 [TRACE] vertex "time_rotating.rotate": visit complete
2022-08-15T14:18:43.834+0100 [TRACE] vertex "time_rotating.rotate": dynamic subgraph completed successfully
2022-08-15T14:18:43.834+0100 [TRACE] vertex "time_rotating.rotate": visit complete
2022-08-15T14:18:43.834+0100 [TRACE] vertex "time_rotating.rotate (expand)": dynamic subgraph completed successfully
2022-08-15T14:18:43.834+0100 [TRACE] vertex "time_rotating.rotate (expand)": visit complete
2022-08-15T14:18:43.834+0100 [TRACE] vertex "local_file.test (expand)": starting visit (*terraform.nodeExpandPlannableResource)
2022-08-15T14:18:43.834+0100 [TRACE] vertex "local_file.test (expand)": expanding dynamic subgraph
2022-08-15T14:18:43.834+0100 [TRACE] vertex "local_file.test (expand)": entering dynamic subgraph
2022-08-15T14:18:43.834+0100 [TRACE] vertex "local_file.test": starting visit (*terraform.NodePlannableResource)
2022-08-15T14:18:43.834+0100 [TRACE] vertex "local_file.test": expanding dynamic subgraph
2022-08-15T14:18:43.834+0100 [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2022-08-15T14:18:43.834+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2022-08-15T14:18:43.834+0100 [TRACE] GRPCProvider: Close
2022-08-15T14:18:43.834+0100 [TRACE] ResourceCountTransformer: adding local_file.test as *terraform.NodePlannableResourceInstance
2022-08-15T14:18:43.834+0100 [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
  local_file.test - *terraform.NodePlannableResourceInstance
  ------
2022-08-15T14:18:43.834+0100 [TRACE] Executing graph transform *terraform.OrphanResourceInstanceCountTransformer
2022-08-15T14:18:43.834+0100 [TRACE] Completed graph transform *terraform.OrphanResourceInstanceCountTransformer (no changes)
2022-08-15T14:18:43.834+0100 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2022-08-15T14:18:43.834+0100 [TRACE] NodeAbstractResourceInstance.AttachResourceState for local_file.test
2022-08-15T14:18:43.834+0100 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2022-08-15T14:18:43.834+0100 [TRACE] Executing graph transform *terraform.TargetsTransformer
2022-08-15T14:18:43.834+0100 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2022-08-15T14:18:43.834+0100 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2022-08-15T14:18:43.834+0100 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-08-15T14:18:43.835+0100 [INFO]  ReferenceTransformer: reference not found: "path.module"
2022-08-15T14:18:43.835+0100 [DEBUG] ReferenceTransformer: "local_file.test" references: []
2022-08-15T14:18:43.835+0100 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2022-08-15T14:18:43.835+0100 [TRACE] Executing graph transform *terraform.RootTransformer
2022-08-15T14:18:43.835+0100 [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2022-08-15T14:18:43.835+0100 [TRACE] vertex "local_file.test": entering dynamic subgraph
2022-08-15T14:18:43.835+0100 [TRACE] vertex "local_file.test": starting visit (*terraform.NodePlannableResourceInstance)
2022-08-15T14:18:43.835+0100 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 pid=57186
2022-08-15T14:18:43.835+0100 [DEBUG] provider: plugin exited
2022-08-15T14:18:43.835+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"] (close)": visit complete
2022-08-15T14:18:43.835+0100 [TRACE] readResourceInstanceState: reading state for local_file.test
2022-08-15T14:18:43.835+0100 [TRACE] upgradeResourceState: schema version of local_file.test is still 0; calling provider "local" for any other minor fixups
2022-08-15T14:18:43.835+0100 [TRACE] GRPCProvider: UpgradeResourceState
2022-08-15T14:18:43.835+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to prevRunState for local_file.test
2022-08-15T14:18:43.835+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for local_file.test
2022-08-15T14:18:43.835+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for local_file.test
2022-08-15T14:18:43.835+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for local_file.test
2022-08-15T14:18:43.835+0100 [TRACE] NodeAbstractResourceInstance.refresh for local_file.test
2022-08-15T14:18:43.836+0100 [TRACE] GRPCProvider: ReadResource
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: tf_req_id=6a2bbf0c-c434-5098-82e1-8afeeb92016e tf_resource_type=local_file tf_provider_addr=provider tf_rpc=UpgradeResourceState @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:707 @module=sdk.proto tf_proto_version=5.2 timestamp=2022-08-15T14:18:43.835+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=6a2bbf0c-c434-5098-82e1-8afeeb92016e @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:714 @module=sdk.proto tf_rpc=UpgradeResourceState tf_resource_type=local_file timestamp=2022-08-15T14:18:43.835+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Upgrading JSON state: @module=sdk.helper_schema tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:323 tf_req_id=6a2bbf0c-c434-5098-82e1-8afeeb92016e tf_resource_type=local_file tf_rpc=UpgradeResourceState timestamp=2022-08-15T14:18:43.835+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: tf_proto_version=5.2 tf_provider_addr=provider tf_resource_type=local_file tf_rpc=UpgradeResourceState @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:720 @module=sdk.proto tf_req_id=6a2bbf0c-c434-5098-82e1-8afeeb92016e timestamp=2022-08-15T14:18:43.835+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: tf_provider_addr=provider @module=sdk.proto tf_proto_version=5.2 tf_req_id=6a2bbf0c-c434-5098-82e1-8afeeb92016e tf_resource_type=local_file tf_rpc=UpgradeResourceState @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:727 timestamp=2022-08-15T14:18:43.835+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_rpc=ReadResource @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:736 tf_req_id=42327621-0e14-9c3c-aa63-d79699d0faae tf_resource_type=local_file timestamp=2022-08-15T14:18:43.836+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_rpc=ReadResource @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:745 tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=42327621-0e14-9c3c-aa63-d79699d0faae tf_resource_type=local_file @module=sdk.proto timestamp=2022-08-15T14:18:43.836+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: @module=sdk.helper_schema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/resource.go:1014 tf_provider_addr=provider tf_req_id=42327621-0e14-9c3c-aa63-d79699d0faae tf_resource_type=local_file tf_rpc=ReadResource timestamp=2022-08-15T14:18:43.836+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: tf_rpc=ReadResource tf_req_id=42327621-0e14-9c3c-aa63-d79699d0faae tf_resource_type=local_file @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/resource.go:1016 @module=sdk.helper_schema tf_provider_addr=provider timestamp=2022-08-15T14:18:43.836+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:751 @module=sdk.proto tf_proto_version=5.2 tf_rpc=ReadResource tf_provider_addr=provider tf_req_id=42327621-0e14-9c3c-aa63-d79699d0faae tf_resource_type=local_file timestamp=2022-08-15T14:18:43.836+0100
2022-08-15T14:18:43.836+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: tf_req_id=42327621-0e14-9c3c-aa63-d79699d0faae tf_resource_type=local_file @module=sdk.proto tf_provider_addr=provider tf_rpc=ReadResource @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:758 tf_proto_version=5.2 timestamp=2022-08-15T14:18:43.836+0100
2022-08-15T14:18:43.836+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for local_file.test
2022-08-15T14:18:43.836+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for local_file.test
2022-08-15T14:18:43.836+0100 [TRACE] Re-validating config for "local_file.test"
2022-08-15T14:18:43.836+0100 [TRACE] GRPCProvider: ValidateResourceConfig
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=ff146a48-6c0d-346e-efbd-a7477a2bbb83 tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:678 @module=sdk.proto tf_resource_type=local_file timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: tf_req_id=ff146a48-6c0d-346e-efbd-a7477a2bbb83 @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:686 tf_provider_addr=provider tf_proto_version=5.2 tf_resource_type=local_file tf_rpc=ValidateResourceTypeConfig @module=sdk.proto timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: @module=sdk.helper_schema tf_req_id=ff146a48-6c0d-346e-efbd-a7477a2bbb83 tf_provider_addr=provider tf_resource_type=local_file tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:245 timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: tf_provider_addr=provider tf_req_id=ff146a48-6c0d-346e-efbd-a7477a2bbb83 tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:247 @module=sdk.helper_schema tf_resource_type=local_file timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] GRPCProvider: PlanResourceChange
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:692 tf_proto_version=5.2 @module=sdk.proto tf_provider_addr=provider tf_req_id=ff146a48-6c0d-346e-efbd-a7477a2bbb83 tf_resource_type=local_file tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:698 tf_proto_version=5.2 tf_resource_type=local_file tf_rpc=ValidateResourceTypeConfig @module=sdk.proto tf_provider_addr=provider tf_req_id=ff146a48-6c0d-346e-efbd-a7477a2bbb83 timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Received request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:767 tf_provider_addr=provider tf_req_id=a4e58cb0-5ed1-f195-183d-e2d52b40fa01 @module=sdk.proto tf_proto_version=5.2 tf_resource_type=local_file tf_rpc=PlanResourceChange timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Calling downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:778 tf_proto_version=5.2 tf_provider_addr=provider tf_resource_type=local_file tf_rpc=PlanResourceChange @module=sdk.proto tf_req_id=a4e58cb0-5ed1-f195-183d-e2d52b40fa01 timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Called downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:784 @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=a4e58cb0-5ed1-f195-183d-e2d52b40fa01 tf_resource_type=local_file tf_rpc=PlanResourceChange timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [TRACE] provider.terraform-provider-local_v2.2.3_x5: Served request: @module=sdk.proto tf_proto_version=5.2 tf_provider_addr=provider tf_req_id=a4e58cb0-5ed1-f195-183d-e2d52b40fa01 @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:791 tf_resource_type=local_file tf_rpc=PlanResourceChange timestamp=2022-08-15T14:18:43.837+0100
2022-08-15T14:18:43.837+0100 [WARN]  Provider "registry.terraform.io/hashicorp/local" produced an invalid plan for local_file.test, but we are tolerating it because it is using the legacy plugin SDK.
    The following problems may be the cause of any confusing errors from downstream operations:
      - .directory_permission: planned value cty.StringVal("0777") for a non-computed attribute
      - .file_permission: planned value cty.StringVal("0777") for a non-computed attribute
2022-08-15T14:18:43.837+0100 [TRACE] writeChange: recorded NoOp change for local_file.test
2022-08-15T14:18:43.837+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for local_file.test
2022-08-15T14:18:43.837+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for local_file.test
2022-08-15T14:18:43.837+0100 [TRACE] vertex "local_file.test": visit complete
2022-08-15T14:18:43.837+0100 [TRACE] vertex "local_file.test": dynamic subgraph completed successfully
2022-08-15T14:18:43.837+0100 [TRACE] vertex "local_file.test": visit complete
2022-08-15T14:18:43.837+0100 [TRACE] vertex "local_file.test (expand)": dynamic subgraph completed successfully
2022-08-15T14:18:43.837+0100 [TRACE] vertex "local_file.test (expand)": visit complete
2022-08-15T14:18:43.837+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/local\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2022-08-15T14:18:43.837+0100 [TRACE] GRPCProvider: Close
2022-08-15T14:18:43.838+0100 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-08-15T14:18:43.838+0100 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/hashicorp/local/2.2.3/darwin_arm64/terraform-provider-local_v2.2.3_x5 pid=57185
2022-08-15T14:18:43.838+0100 [DEBUG] provider: plugin exited
2022-08-15T14:18:43.838+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/local\"] (close)": visit complete
2022-08-15T14:18:43.838+0100 [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2022-08-15T14:18:43.838+0100 [TRACE] vertex "root": visit complete
2022-08-15T14:18:43.838+0100 [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/hashicorp/local"
2022-08-15T14:18:43.838+0100 [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/hashicorp/time"
2022-08-15T14:18:43.838+0100 [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/hashicorp/local"
2022-08-15T14:18:43.838+0100 [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/hashicorp/time"
2022-08-15T14:18:43.838+0100 [DEBUG] building apply graph to check for errors
2022-08-15T14:18:43.838+0100 [TRACE] Executing graph transform *terraform.ConfigTransformer
2022-08-15T14:18:43.838+0100 [TRACE] ConfigTransformer: Starting for path:
2022-08-15T14:18:43.838+0100 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  ------
2022-08-15T14:18:43.838+0100 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2022-08-15T14:18:43.838+0100 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2022-08-15T14:18:43.838+0100 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2022-08-15T14:18:43.838+0100 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2022-08-15T14:18:43.838+0100 [TRACE] Executing graph transform *terraform.LocalTransformer
2022-08-15T14:18:43.838+0100 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2022-08-15T14:18:43.838+0100 [TRACE] Executing graph transform *terraform.OutputTransformer
2022-08-15T14:18:43.838+0100 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2022-08-15T14:18:43.838+0100 [TRACE] Executing graph transform *terraform.DiffTransformer
2022-08-15T14:18:43.839+0100 [TRACE] DiffTransformer starting
2022-08-15T14:18:43.839+0100 [TRACE] DiffTransformer: found Create change for time_rotating.rotate
2022-08-15T14:18:43.839+0100 [TRACE] DiffTransformer: time_rotating.rotate will be represented by time_rotating.rotate
2022-08-15T14:18:43.839+0100 [TRACE] DiffTransformer: found NoOp change for local_file.test
2022-08-15T14:18:43.839+0100 [TRACE] DiffTransformer complete
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.DiffTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  ------
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2022-08-15T14:18:43.839+0100 [DEBUG] Resource state not found for node "time_rotating.rotate", instance time_rotating.rotate
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2022-08-15T14:18:43.839+0100 [TRACE] AttachResourceConfigTransformer: attaching to "time_rotating.rotate (expand)" (*terraform.nodeExpandApplyableResource) config from main.tf:1,1-34
2022-08-15T14:18:43.839+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to time_rotating.rotate (expand)
2022-08-15T14:18:43.839+0100 [TRACE] AttachResourceConfigTransformer: attaching to "local_file.test (expand)" (*terraform.nodeExpandApplyableResource) config from main.tf:11,1-29
2022-08-15T14:18:43.839+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to local_file.test (expand)
2022-08-15T14:18:43.839+0100 [TRACE] AttachResourceConfigTransformer: attaching to "time_rotating.rotate" (*terraform.NodeApplyableResourceInstance) config from main.tf:1,1-34
2022-08-15T14:18:43.839+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to time_rotating.rotate
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2022-08-15T14:18:43.839+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2022-08-15T14:18:43.839+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  ------
2022-08-15T14:18:43.839+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2022-08-15T14:18:43.839+0100 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/time"], implied first by time_rotating.rotate (expand)
2022-08-15T14:18:43.839+0100 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/local"], implied first by local_file.test (expand)
2022-08-15T14:18:43.839+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  ------
2022-08-15T14:18:43.839+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2022-08-15T14:18:43.839+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/local"] serving local_file.test (expand)
2022-08-15T14:18:43.839+0100 [DEBUG] ProviderTransformer: "local_file.test (expand)" (*terraform.nodeExpandApplyableResource) needs provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.839+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/time"] serving time_rotating.rotate
2022-08-15T14:18:43.839+0100 [DEBUG] ProviderTransformer: "time_rotating.rotate" (*terraform.NodeApplyableResourceInstance) needs provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.839+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/time"] serving time_rotating.rotate (expand)
2022-08-15T14:18:43.839+0100 [DEBUG] ProviderTransformer: "time_rotating.rotate (expand)" (*terraform.nodeExpandApplyableResource) needs provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.839+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.839+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2022-08-15T14:18:43.839+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2022-08-15T14:18:43.839+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to time_rotating.rotate (expand)
2022-08-15T14:18:43.839+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to local_file.test (expand)
2022-08-15T14:18:43.839+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to time_rotating.rotate
2022-08-15T14:18:43.839+0100 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.839+0100 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2022-08-15T14:18:43.839+0100 [DEBUG] ReferenceTransformer: "time_rotating.rotate (expand)" references: []
2022-08-15T14:18:43.839+0100 [DEBUG] ReferenceTransformer: "local_file.test (expand)" references: []
2022-08-15T14:18:43.839+0100 [DEBUG] ReferenceTransformer: "time_rotating.rotate" references: []
2022-08-15T14:18:43.839+0100 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/time\"]" references: []
2022-08-15T14:18:43.839+0100 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/local\"]" references: []
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2022-08-15T14:18:43.839+0100 [TRACE] AttachDependenciesTransformer: time_rotating.rotate depends on []
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2022-08-15T14:18:43.839+0100 [TRACE] ForcedCBDTransformer: "time_rotating.rotate" (*terraform.NodeApplyableResourceInstance) has no CBD descendent, so skipping
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.DestroyEdgeTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.DestroyEdgeTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.CBDEdgeTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.CBDEdgeTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.pruneUnusedNodesTransformer
2022-08-15T14:18:43.839+0100 [DEBUG] pruneUnusedNodes: local_file.test (expand) is no longer needed, removing
2022-08-15T14:18:43.839+0100 [DEBUG] pruneUnusedNodes: provider["registry.terraform.io/hashicorp/local"] is no longer needed, removing
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.pruneUnusedNodesTransformer with new graph:
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.TargetsTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.839+0100 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2022-08-15T14:18:43.839+0100 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    time_rotating.rotate - *terraform.NodeApplyableResourceInstance
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.839+0100 [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/hashicorp/local"
2022-08-15T14:18:43.839+0100 [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/hashicorp/time"
2022-08-15T14:18:43.840+0100 [INFO]  backend/local: apply calling Apply
2022-08-15T14:18:43.840+0100 [DEBUG] Building and walking apply graph for NormalMode plan
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.ConfigTransformer
2022-08-15T14:18:43.840+0100 [TRACE] ConfigTransformer: Starting for path:
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  ------
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.RootVariableTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.LocalTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.OutputTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.DiffTransformer
2022-08-15T14:18:43.840+0100 [TRACE] DiffTransformer starting
2022-08-15T14:18:43.840+0100 [TRACE] DiffTransformer: found Create change for time_rotating.rotate
2022-08-15T14:18:43.840+0100 [TRACE] DiffTransformer: time_rotating.rotate will be represented by time_rotating.rotate
2022-08-15T14:18:43.840+0100 [TRACE] DiffTransformer: found NoOp change for local_file.test
2022-08-15T14:18:43.840+0100 [TRACE] DiffTransformer complete
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.DiffTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  ------
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.AttachStateTransformer
2022-08-15T14:18:43.840+0100 [DEBUG] Resource state not found for node "time_rotating.rotate", instance time_rotating.rotate
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2022-08-15T14:18:43.840+0100 [TRACE] AttachResourceConfigTransformer: attaching to "time_rotating.rotate (expand)" (*terraform.nodeExpandApplyableResource) config from main.tf:1,1-34
2022-08-15T14:18:43.840+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to time_rotating.rotate (expand)
2022-08-15T14:18:43.840+0100 [TRACE] AttachResourceConfigTransformer: attaching to "local_file.test (expand)" (*terraform.nodeExpandApplyableResource) config from main.tf:11,1-29
2022-08-15T14:18:43.840+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to local_file.test (expand)
2022-08-15T14:18:43.840+0100 [TRACE] AttachResourceConfigTransformer: attaching to "time_rotating.rotate" (*terraform.NodeApplyableResourceInstance) config from main.tf:1,1-34
2022-08-15T14:18:43.840+0100 [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to time_rotating.rotate
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.graphTransformerMulti
2022-08-15T14:18:43.840+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2022-08-15T14:18:43.840+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  ------
2022-08-15T14:18:43.840+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2022-08-15T14:18:43.840+0100 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/local"], implied first by local_file.test (expand)
2022-08-15T14:18:43.840+0100 [DEBUG] adding implicit provider configuration provider["registry.terraform.io/hashicorp/time"], implied first by time_rotating.rotate
2022-08-15T14:18:43.840+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  ------
2022-08-15T14:18:43.840+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2022-08-15T14:18:43.840+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/time"] serving time_rotating.rotate (expand)
2022-08-15T14:18:43.840+0100 [DEBUG] ProviderTransformer: "time_rotating.rotate (expand)" (*terraform.nodeExpandApplyableResource) needs provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.840+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/local"] serving local_file.test (expand)
2022-08-15T14:18:43.840+0100 [DEBUG] ProviderTransformer: "local_file.test (expand)" (*terraform.nodeExpandApplyableResource) needs provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.840+0100 [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/hashicorp/time"] serving time_rotating.rotate
2022-08-15T14:18:43.840+0100 [DEBUG] ProviderTransformer: "time_rotating.rotate" (*terraform.NodeApplyableResourceInstance) needs provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.840+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.840+0100 [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2022-08-15T14:18:43.840+0100 [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  local_file.test (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/local"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2022-08-15T14:18:43.840+0100 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/local"]
2022-08-15T14:18:43.840+0100 [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.840+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to time_rotating.rotate (expand)
2022-08-15T14:18:43.840+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to local_file.test (expand)
2022-08-15T14:18:43.840+0100 [TRACE] AttachSchemaTransformer: attaching resource schema to time_rotating.rotate
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.ReferenceTransformer
2022-08-15T14:18:43.840+0100 [DEBUG] ReferenceTransformer: "time_rotating.rotate" references: []
2022-08-15T14:18:43.840+0100 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/local\"]" references: []
2022-08-15T14:18:43.840+0100 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/time\"]" references: []
2022-08-15T14:18:43.840+0100 [DEBUG] ReferenceTransformer: "time_rotating.rotate (expand)" references: []
2022-08-15T14:18:43.840+0100 [DEBUG] ReferenceTransformer: "local_file.test (expand)" references: []
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2022-08-15T14:18:43.840+0100 [TRACE] AttachDependenciesTransformer: time_rotating.rotate depends on []
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2022-08-15T14:18:43.840+0100 [TRACE] ForcedCBDTransformer: "time_rotating.rotate" (*terraform.NodeApplyableResourceInstance) has no CBD descendent, so skipping
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.DestroyEdgeTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.DestroyEdgeTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.CBDEdgeTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.CBDEdgeTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.pruneUnusedNodesTransformer
2022-08-15T14:18:43.840+0100 [DEBUG] pruneUnusedNodes: local_file.test (expand) is no longer needed, removing
2022-08-15T14:18:43.840+0100 [DEBUG] pruneUnusedNodes: provider["registry.terraform.io/hashicorp/local"] is no longer needed, removing
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.pruneUnusedNodesTransformer with new graph:
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.TargetsTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.840+0100 [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2022-08-15T14:18:43.840+0100 [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
    time_rotating.rotate - *terraform.NodeApplyableResourceInstance
  root - *terraform.nodeCloseModule
    provider["registry.terraform.io/hashicorp/time"] (close) - *terraform.graphNodeCloseProvider
  time_rotating.rotate - *terraform.NodeApplyableResourceInstance
    time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
  time_rotating.rotate (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/hashicorp/time"] - *terraform.NodeApplyableProvider
  ------
2022-08-15T14:18:43.840+0100 [DEBUG] Starting graph walk: walkApply
2022-08-15T14:18:43.840+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"]": starting visit (*terraform.NodeApplyableProvider)
2022-08-15T14:18:43.840+0100 [DEBUG] created provider logger: level=trace
2022-08-15T14:18:43.840+0100 [INFO]  provider: configuring client automatic mTLS
2022-08-15T14:18:43.844+0100 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 args=[.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5]
2022-08-15T14:18:43.845+0100 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 pid=57187
2022-08-15T14:18:43.845+0100 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5
2022-08-15T14:18:43.849+0100 [INFO]  provider.terraform-provider-time_v0.8.0_x5: configuring server automatic mTLS: timestamp=2022-08-15T14:18:43.849+0100
2022-08-15T14:18:43.858+0100 [DEBUG] provider: using plugin: version=5
2022-08-15T14:18:43.858+0100 [DEBUG] provider.terraform-provider-time_v0.8.0_x5: plugin address: address=/var/folders/x3/kc25vbrj7dvdm9rr8d1kzyvm0000gr/T/plugin836663061 network=unix timestamp=2022-08-15T14:18:43.858+0100
2022-08-15T14:18:43.866+0100 [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/hashicorp/time\"]" provider for provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.866+0100 [TRACE] NodeApplyableProvider: configuring provider["registry.terraform.io/hashicorp/time"]
2022-08-15T14:18:43.866+0100 [TRACE] buildProviderConfig for provider["registry.terraform.io/hashicorp/time"]: no configuration at all
2022-08-15T14:18:43.866+0100 [TRACE] GRPCProvider: GetProviderSchema
2022-08-15T14:18:43.866+0100 [TRACE] provider.stdio: waiting for stdio data
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_proto_version=5.3 tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:491 @module=sdk.proto tf_provider_addr=provider tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: tf_provider_addr=provider tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Getting provider schema: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:70 timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c tf_resource_type=time_offset tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c tf_resource_type=time_rotating @module=sdk.helper_schema tf_provider_addr=provider tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: tf_provider_addr=provider tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c tf_resource_type=time_sleep tf_rpc=GetProviderSchema @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Found resource type: tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c tf_resource_type=time_static @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:86 @module=sdk.helper_schema tf_provider_addr=provider tf_rpc=GetProviderSchema timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: @module=sdk.proto diagnostic_warning_count=0 tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 diagnostic_error_count=0 tf_req_duration_ms=0 timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.866+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=8f4a9227-f086-0056-b443-c51d48cfbd7c tf_rpc=GetProviderSchema @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:510 timestamp=2022-08-15T14:18:43.866+0100
2022-08-15T14:18:43.867+0100 [TRACE] GRPCProvider: ValidateProviderConfig
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_provider_addr=provider tf_req_id=5f80a14a-c8b7-45b8-e467-fa1a410e8d3c tf_rpc=PrepareProviderConfig @module=sdk.proto tf_proto_version=5.3 @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:518 timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Skipping protocol data file writing because no data directory is set. Use the TF_LOG_SDK_PROTO_DATA_DIR environment variable to enable this functionality.: @caller=github.com/hashicorp/[email protected]/internal/logging/protocol_data.go:38 tf_proto_version=5.3 tf_rpc=PrepareProviderConfig tf_req_id=5f80a14a-c8b7-45b8-e467-fa1a410e8d3c @module=sdk.proto tf_provider_addr=provider timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: tf_provider_addr=provider tf_rpc=PrepareProviderConfig @module=sdk.proto tf_proto_version=5.3 tf_req_id=5f80a14a-c8b7-45b8-e467-fa1a410e8d3c @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Preparing provider configuration: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=5f80a14a-c8b7-45b8-e467-fa1a410e8d3c tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:128 timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:216 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=5f80a14a-c8b7-45b8-e467-fa1a410e8d3c timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:218 @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=5f80a14a-c8b7-45b8-e467-fa1a410e8d3c timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: @module=sdk.proto tf_proto_version=5.3 tf_req_duration_ms=0 tf_req_id=5f80a14a-c8b7-45b8-e467-fa1a410e8d3c @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 diagnostic_error_count=0 diagnostic_warning_count=0 tf_provider_addr=provider tf_rpc=PrepareProviderConfig timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:539 @module=sdk.proto tf_provider_addr=provider tf_rpc=PrepareProviderConfig tf_proto_version=5.3 tf_req_id=5f80a14a-c8b7-45b8-e467-fa1a410e8d3c timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] GRPCProvider: ConfigureProvider
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_req_id=081a6120-0c39-4c79-b018-b610e15162cd tf_rpc=Configure tf_proto_version=5.3 tf_provider_addr=provider @module=sdk.proto @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:547 timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: tf_req_id=081a6120-0c39-4c79-b018-b610e15162cd tf_rpc=Configure @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=081a6120-0c39-4c79-b018-b610e15162cd tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:556 timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: tf_provider_addr=provider tf_req_id=081a6120-0c39-4c79-b018-b610e15162cd tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:558 @module=sdk.helper_schema timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: diagnostic_error_count=0 tf_proto_version=5.3 tf_req_duration_ms=0 @module=sdk.proto diagnostic_warning_count=0 tf_provider_addr=provider tf_req_id=081a6120-0c39-4c79-b018-b610e15162cd @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 tf_rpc=Configure timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.867+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=Configure @module=sdk.proto @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:567 tf_req_id=081a6120-0c39-4c79-b018-b610e15162cd timestamp=2022-08-15T14:18:43.867+0100
2022-08-15T14:18:43.868+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"]": visit complete
2022-08-15T14:18:43.868+0100 [TRACE] vertex "time_rotating.rotate (expand)": starting visit (*terraform.nodeExpandApplyableResource)
2022-08-15T14:18:43.868+0100 [TRACE] vertex "time_rotating.rotate (expand)": expanding dynamic subgraph
2022-08-15T14:18:43.868+0100 [TRACE] vertex "time_rotating.rotate (expand)": entering dynamic subgraph
2022-08-15T14:18:43.868+0100 [TRACE] vertex "time_rotating.rotate": starting visit (*terraform.NodeApplyableResource)
2022-08-15T14:18:43.868+0100 [TRACE] vertex "time_rotating.rotate": visit complete
2022-08-15T14:18:43.868+0100 [TRACE] vertex "time_rotating.rotate (expand)": dynamic subgraph completed successfully
2022-08-15T14:18:43.868+0100 [TRACE] vertex "time_rotating.rotate (expand)": visit complete
2022-08-15T14:18:43.868+0100 [TRACE] vertex "time_rotating.rotate": starting visit (*terraform.NodeApplyableResourceInstance)
2022-08-15T14:18:43.868+0100 [TRACE] readDiff: Read Create change from plan for time_rotating.rotate
2022-08-15T14:18:43.868+0100 [TRACE] readResourceInstanceState: reading state for time_rotating.rotate
2022-08-15T14:18:43.868+0100 [TRACE] readResourceInstanceState: no state present for time_rotating.rotate
2022-08-15T14:18:43.868+0100 [TRACE] readDiff: Read Create change from plan for time_rotating.rotate
2022-08-15T14:18:43.868+0100 [TRACE] Re-validating config for "time_rotating.rotate"
2022-08-15T14:18:43.868+0100 [TRACE] GRPCProvider: ValidateResourceConfig
2022-08-15T14:18:43.868+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:679 @module=sdk.proto tf_req_id=4158694f-26bd-3a8a-6fdb-e9530ff82c9f tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.868+0100
2022-08-15T14:18:43.868+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_provider_addr=provider tf_req_id=4158694f-26bd-3a8a-6fdb-e9530ff82c9f tf_resource_type=time_rotating tf_proto_version=5.3 tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.868+0100
2022-08-15T14:18:43.868+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:245 tf_provider_addr=provider @module=sdk.helper_schema tf_req_id=4158694f-26bd-3a8a-6fdb-e9530ff82c9f tf_resource_type=time_rotating tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.868+0100
2022-08-15T14:18:43.868+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/grpc_provider.go:247 @module=sdk.helper_schema tf_req_id=4158694f-26bd-3a8a-6fdb-e9530ff82c9f tf_rpc=ValidateResourceTypeConfig tf_provider_addr=provider tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.868+0100
2022-08-15T14:18:43.868+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: @module=sdk.proto diagnostic_error_count=0 tf_proto_version=5.3 tf_provider_addr=provider tf_req_duration_ms=0 tf_req_id=4158694f-26bd-3a8a-6fdb-e9530ff82c9f tf_rpc=ValidateResourceTypeConfig tf_resource_type=time_rotating diagnostic_warning_count=0 @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 timestamp=2022-08-15T14:18:43.868+0100
2022-08-15T14:18:43.868+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: tf_proto_version=5.3 @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:699 @module=sdk.proto tf_provider_addr=provider tf_req_id=4158694f-26bd-3a8a-6fdb-e9530ff82c9f tf_resource_type=time_rotating tf_rpc=ValidateResourceTypeConfig timestamp=2022-08-15T14:18:43.868+0100
2022-08-15T14:18:43.868+0100 [TRACE] GRPCProvider: PlanResourceChange
2022-08-15T14:18:43.869+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:768 tf_req_id=3383ab87-9d95-808a-19ca-22af7cc0a63b tf_resource_type=time_rotating @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=PlanResourceChange timestamp=2022-08-15T14:18:43.869+0100
2022-08-15T14:18:43.869+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: tf_provider_addr=provider tf_resource_type=time_rotating tf_req_id=3383ab87-9d95-808a-19ca-22af7cc0a63b tf_rpc=PlanResourceChange @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 timestamp=2022-08-15T14:18:43.869+0100
2022-08-15T14:18:43.869+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/schema.go:697 tf_rpc=PlanResourceChange @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=3383ab87-9d95-808a-19ca-22af7cc0a63b tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.869+0100
2022-08-15T14:18:43.869+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: tf_provider_addr=provider @module=sdk.helper_schema tf_req_id=3383ab87-9d95-808a-19ca-22af7cc0a63b tf_resource_type=time_rotating tf_rpc=PlanResourceChange @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/schema.go:699 timestamp=2022-08-15T14:18:43.869+0100
2022-08-15T14:18:43.869+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: diagnostic_warning_count=0 tf_provider_addr=provider tf_resource_type=time_rotating tf_req_id=3383ab87-9d95-808a-19ca-22af7cc0a63b tf_rpc=PlanResourceChange @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 @module=sdk.proto diagnostic_error_count=0 tf_proto_version=5.3 tf_req_duration_ms=0 timestamp=2022-08-15T14:18:43.869+0100
2022-08-15T14:18:43.869+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: tf_proto_version=5.3 tf_req_id=3383ab87-9d95-808a-19ca-22af7cc0a63b tf_rpc=PlanResourceChange @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:792 tf_provider_addr=provider tf_resource_type=time_rotating @module=sdk.proto timestamp=2022-08-15T14:18:43.869+0100
2022-08-15T14:18:43.869+0100 [TRACE] checkPlannedChange: Verifying that actual change (action Create) matches planned change (action Create)
2022-08-15T14:18:43.869+0100 [INFO]  Starting apply for time_rotating.rotate
2022-08-15T14:18:43.869+0100 [DEBUG] time_rotating.rotate: applying the planned Create change
2022-08-15T14:18:43.869+0100 [TRACE] GRPCProvider: ApplyResourceChange
2022-08-15T14:18:43.870+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received request: tf_rpc=ApplyResourceChange @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:801 tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=ee89cef8-4018-d6a2-7ef3-bd438491074f tf_resource_type=time_rotating @module=sdk.proto timestamp=2022-08-15T14:18:43.870+0100
2022-08-15T14:18:43.870+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Sending request downstream: @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 tf_provider_addr=provider tf_rpc=ApplyResourceChange @module=sdk.proto tf_proto_version=5.3 tf_req_id=ee89cef8-4018-d6a2-7ef3-bd438491074f tf_resource_type=time_rotating timestamp=2022-08-15T14:18:43.870+0100
2022-08-15T14:18:43.870+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Calling downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/resource.go:836 tf_resource_type=time_rotating tf_rpc=ApplyResourceChange @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=ee89cef8-4018-d6a2-7ef3-bd438491074f timestamp=2022-08-15T14:18:43.870+0100
2022-08-15T14:18:43.870+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Called downstream: @module=sdk.helper_schema tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-sdk/[email protected]/helper/schema/resource.go:838 tf_resource_type=time_rotating tf_rpc=ApplyResourceChange tf_req_id=ee89cef8-4018-d6a2-7ef3-bd438491074f timestamp=2022-08-15T14:18:43.870+0100
2022-08-15T14:18:43.870+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Received downstream response: tf_proto_version=5.3 tf_req_duration_ms=0 tf_req_id=ee89cef8-4018-d6a2-7ef3-bd438491074f tf_resource_type=time_rotating tf_rpc=ApplyResourceChange @caller=github.com/hashicorp/[email protected]/tfprotov5/internal/tf5serverlogging/downstream_request.go:38 @module=sdk.proto diagnostic_error_count=0 diagnostic_warning_count=0 tf_provider_addr=provider timestamp=2022-08-15T14:18:43.870+0100
2022-08-15T14:18:43.870+0100 [TRACE] provider.terraform-provider-time_v0.8.0_x5: Served request: tf_rpc=ApplyResourceChange @caller=github.com/hashicorp/[email protected]/tfprotov5/tf5server/server.go:825 tf_provider_addr=provider tf_req_id=ee89cef8-4018-d6a2-7ef3-bd438491074f tf_resource_type=time_rotating @module=sdk.proto tf_proto_version=5.3 timestamp=2022-08-15T14:18:43.870+0100
2022-08-15T14:18:43.870+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for time_rotating.rotate
2022-08-15T14:18:43.870+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for time_rotating.rotate
2022-08-15T14:18:43.870+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for time_rotating.rotate
2022-08-15T14:18:43.870+0100 [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for time_rotating.rotate
2022-08-15T14:18:43.870+0100 [TRACE] statemgr.Filesystem: creating backup snapshot at terraform.tfstate.backup
2022-08-15T14:18:43.871+0100 [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 33
2022-08-15T14:18:43.871+0100 [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2022-08-15T14:18:43.891+0100 [TRACE] vertex "time_rotating.rotate": visit complete
2022-08-15T14:18:43.891+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2022-08-15T14:18:43.891+0100 [TRACE] GRPCProvider: Close
2022-08-15T14:18:43.892+0100 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-08-15T14:18:43.892+0100 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/hashicorp/time/0.8.0/darwin_arm64/terraform-provider-time_v0.8.0_x5 pid=57187
2022-08-15T14:18:43.892+0100 [DEBUG] provider: plugin exited
2022-08-15T14:18:43.892+0100 [TRACE] vertex "provider[\"registry.terraform.io/hashicorp/time\"] (close)": visit complete
2022-08-15T14:18:43.892+0100 [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2022-08-15T14:18:43.892+0100 [TRACE] vertex "root": visit complete
2022-08-15T14:18:43.892+0100 [TRACE] statemgr.Filesystem: have already backed up original terraform.tfstate to terraform.tfstate.backup on a previous write
2022-08-15T14:18:43.892+0100 [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 34
2022-08-15T14:18:43.892+0100 [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2022-08-15T14:18:43.911+0100 [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2022-08-15T14:18:43.911+0100 [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock