Skip to content

Instantly share code, notes, and snippets.

@mazrean

mazrean/apply_log.txt

Created January 10, 2022 04:15
Show Gist options
  • Save mazrean/7dc9060c975e7deab108d915298ce4ce to your computer and use it in GitHub Desktop.
Save mazrean/7dc9060c975e7deab108d915298ce4ce to your computer and use it in GitHub Desktop.
Download ZIP
Raw
apply_log.txt
❯ TF_LOG=DEBUG terraform apply
2022-01-10T13:12:00.960+0900 [INFO] Terraform version: 1.1.3
2022-01-10T13:12:00.960+0900 [INFO] Go runtime version: go1.17.2
2022-01-10T13:12:00.960+0900 [INFO] CLI args: []string{"/Users/mazrean/.asdf/installs/terraform/1.1.3/bin/terraform", "apply"}
2022-01-10T13:12:00.961+0900 [DEBUG] Attempting to open CLI config file: /Users/mazrean/.terraformrc
2022-01-10T13:12:00.961+0900 [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2022-01-10T13:12:00.961+0900 [INFO] Loading CLI configuration from /Users/mazrean/.terraform.d/credentials.tfrc.json
2022-01-10T13:12:00.961+0900 [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2022-01-10T13:12:00.961+0900 [DEBUG] ignoring non-existing provider search directory /Users/mazrean/.terraform.d/plugins
2022-01-10T13:12:00.961+0900 [DEBUG] ignoring non-existing provider search directory /Users/mazrean/Library/Application Support/io.terraform/plugins
2022-01-10T13:12:00.961+0900 [DEBUG] ignoring non-existing provider search directory /Library/Application Support/io.terraform/plugins
2022-01-10T13:12:00.962+0900 [INFO] CLI command args: []string{"apply"}
2022-01-10T13:12:00.966+0900 [DEBUG] New state was assigned lineage "a0c7595d-6953-9ee4-bd31-7adacb4c8456"
2022-01-10T13:12:00.993+0900 [DEBUG] checking for provisioner in "."
2022-01-10T13:12:00.993+0900 [DEBUG] checking for provisioner in "/Users/mazrean/.asdf/installs/terraform/1.1.3/bin"
2022-01-10T13:12:00.995+0900 [INFO] backend/local: starting Apply operation
2022-01-10T13:12:00.997+0900 [DEBUG] created provider logger: level=debug
2022-01-10T13:12:00.997+0900 [INFO] provider: configuring client automatic mTLS
2022-01-10T13:12:01.005+0900 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 args=[.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0]
2022-01-10T13:12:01.007+0900 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 pid=10400
2022-01-10T13:12:01.007+0900 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0
2022-01-10T13:12:01.027+0900 [INFO] provider.terraform-provider-docker_v2.15.0: configuring server automatic mTLS: timestamp=2022-01-10T13:12:01.027+0900
2022-01-10T13:12:01.049+0900 [DEBUG] provider: using plugin: version=5
2022-01-10T13:12:01.049+0900 [DEBUG] provider.terraform-provider-docker_v2.15.0: plugin address: address=/var/folders/h5/cfl1y25d5f15br56gpm06fdw0000gn/T/plugin979197175 network=unix timestamp=2022-01-10T13:12:01.048+0900
2022-01-10T13:12:01.073+0900 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-01-10T13:12:01.074+0900 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 pid=10400
2022-01-10T13:12:01.074+0900 [DEBUG] provider: plugin exited
2022-01-10T13:12:01.074+0900 [DEBUG] Building and walking validate graph
2022-01-10T13:12:01.074+0900 [DEBUG] ProviderTransformer: "docker_registry_image.registry_image" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/kreuzwerker/docker"]
2022-01-10T13:12:01.075+0900 [INFO] ReferenceTransformer: reference not found: "path.cwd"
2022-01-10T13:12:01.075+0900 [DEBUG] ReferenceTransformer: "docker_registry_image.registry_image" references: []
2022-01-10T13:12:01.075+0900 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/kreuzwerker/docker\"]" references: []
2022-01-10T13:12:01.075+0900 [DEBUG] Starting graph walk: walkValidate
2022-01-10T13:12:01.076+0900 [DEBUG] created provider logger: level=debug
2022-01-10T13:12:01.076+0900 [INFO] provider: configuring client automatic mTLS
2022-01-10T13:12:01.083+0900 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 args=[.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0]
2022-01-10T13:12:01.085+0900 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 pid=10401
2022-01-10T13:12:01.085+0900 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0
2022-01-10T13:12:01.095+0900 [INFO] provider.terraform-provider-docker_v2.15.0: configuring server automatic mTLS: timestamp=2022-01-10T13:12:01.094+0900
2022-01-10T13:12:01.118+0900 [DEBUG] provider: using plugin: version=5
2022-01-10T13:12:01.119+0900 [DEBUG] provider.terraform-provider-docker_v2.15.0: plugin address: network=unix address=/var/folders/h5/cfl1y25d5f15br56gpm06fdw0000gn/T/plugin493889444 timestamp=2022-01-10T13:12:01.118+0900
2022-01-10T13:12:01.142+0900 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-01-10T13:12:01.143+0900 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 pid=10401
2022-01-10T13:12:01.143+0900 [DEBUG] provider: plugin exited
2022-01-10T13:12:01.143+0900 [INFO] backend/local: apply calling Plan
2022-01-10T13:12:01.143+0900 [DEBUG] Building and walking plan graph for NormalMode
2022-01-10T13:12:01.143+0900 [DEBUG] ProviderTransformer: "docker_registry_image.registry_image (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/kreuzwerker/docker"]
2022-01-10T13:12:01.143+0900 [INFO] ReferenceTransformer: reference not found: "path.cwd"
2022-01-10T13:12:01.143+0900 [DEBUG] ReferenceTransformer: "docker_registry_image.registry_image (expand)" references: []
2022-01-10T13:12:01.143+0900 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/kreuzwerker/docker\"]" references: []
2022-01-10T13:12:01.143+0900 [DEBUG] Starting graph walk: walkPlan
2022-01-10T13:12:01.143+0900 [DEBUG] created provider logger: level=debug
2022-01-10T13:12:01.143+0900 [INFO] provider: configuring client automatic mTLS
2022-01-10T13:12:01.150+0900 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 args=[.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0]
2022-01-10T13:12:01.152+0900 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 pid=10402
2022-01-10T13:12:01.152+0900 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0
2022-01-10T13:12:01.163+0900 [INFO] provider.terraform-provider-docker_v2.15.0: configuring server automatic mTLS: timestamp=2022-01-10T13:12:01.163+0900
2022-01-10T13:12:01.185+0900 [DEBUG] provider.terraform-provider-docker_v2.15.0: plugin address: address=/var/folders/h5/cfl1y25d5f15br56gpm06fdw0000gn/T/plugin172570233 network=unix timestamp=2022-01-10T13:12:01.185+0900
2022-01-10T13:12:01.185+0900 [DEBUG] provider: using plugin: version=5
2022-01-10T13:12:01.211+0900 [WARN] ValidateProviderConfig from "provider[\"registry.terraform.io/kreuzwerker/docker\"]" changed the config value, but that value is unused
2022-01-10T13:12:01.226+0900 [INFO] provider.terraform-provider-docker_v2.15.0: 2022/01/10 13:12:01 [DEBUG] Using username for registry auths: mazrean: timestamp=2022-01-10T13:12:01.226+0900
2022-01-10T13:12:01.227+0900 [DEBUG] Resource instance state not found for node "docker_registry_image.registry_image", instance docker_registry_image.registry_image
2022-01-10T13:12:01.227+0900 [INFO] ReferenceTransformer: reference not found: "path.cwd"
2022-01-10T13:12:01.227+0900 [DEBUG] ReferenceTransformer: "docker_registry_image.registry_image" references: []
2022-01-10T13:12:01.227+0900 [DEBUG] refresh: docker_registry_image.registry_image: no state, so not refreshing
2022-01-10T13:12:01.264+0900 [WARN] Provider "registry.terraform.io/kreuzwerker/docker" produced an invalid plan for docker_registry_image.registry_image, 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:
- .insecure_skip_verify: planned value cty.False for a non-computed attribute
- .keep_remotely: planned value cty.False for a non-computed attribute
- .build[0].dockerfile: planned value cty.StringVal("Dockerfile") for a non-computed attribute
- .build[0].context: planned value cty.StringVal("/Users/mazrean/programs/teraform/ghcr-sample:17ee4784eac8734686818753f4c5851ace8cd387070f99144fc82ae34c295f0c") does not match config value cty.StringVal("/Users/mazrean/programs/teraform/ghcr-sample")
2022-01-10T13:12:01.265+0900 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-01-10T13:12:01.266+0900 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 pid=10402
2022-01-10T13:12:01.266+0900 [DEBUG] provider: plugin exited
Terraform used the selected providers to generate the following execution plan.
Resource actions are indicated with the following symbols:
+ create
Terraform will perform the following actions:
# docker_registry_image.registry_image will be created
+ resource "docker_registry_image" "registry_image" {
+ id = (known after apply)
+ insecure_skip_verify = false
+ keep_remotely = false
+ name = "ghcr.io/mazrean/helloworld:1.0"
+ sha256_digest = (known after apply)
+ build {
+ context = "/Users/mazrean/programs/teraform/ghcr-sample:17ee4784eac8734686818753f4c5851ace8cd387070f99144fc82ae34c295f0c"
+ dockerfile = "Dockerfile"
}
}
Plan: 1 to add, 0 to change, 0 to destroy.
2022-01-10T13:12:01.268+0900 [DEBUG] command: asking for input: "\nDo you want to perform these actions?"
Do you want to perform these actions?
Terraform will perform the actions described above.
Only 'yes' will be accepted to approve.
Enter a value: 2022-01-10T13:12:03.964+0900 [ERROR] Checkpoint error: Get "https://checkpoint-api.hashicorp.com/v1/check/terraform?arch=arm64&os=darwin&signature=7a95a208-292f-2915-8403-103185f4fbc8&version=1.1.3": context deadline exceeded (Client.Timeout exceeded while awaiting headers)
yes
2022-01-10T13:12:09.382+0900 [INFO] backend/local: apply calling Apply
2022-01-10T13:12:09.383+0900 [DEBUG] Building and walking apply graph for NormalMode plan
2022-01-10T13:12:09.383+0900 [DEBUG] Resource state not found for node "docker_registry_image.registry_image", instance docker_registry_image.registry_image
2022-01-10T13:12:09.384+0900 [DEBUG] ProviderTransformer: "docker_registry_image.registry_image (expand)" (*terraform.nodeExpandApplyableResource) needs provider["registry.terraform.io/kreuzwerker/docker"]
2022-01-10T13:12:09.385+0900 [DEBUG] ProviderTransformer: "docker_registry_image.registry_image" (*terraform.NodeApplyableResourceInstance) needs provider["registry.terraform.io/kreuzwerker/docker"]
2022-01-10T13:12:09.385+0900 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/kreuzwerker/docker\"]" references: []
2022-01-10T13:12:09.385+0900 [DEBUG] ReferenceTransformer: "docker_registry_image.registry_image (expand)" references: []
2022-01-10T13:12:09.385+0900 [INFO] ReferenceTransformer: reference not found: "path.cwd"
2022-01-10T13:12:09.385+0900 [DEBUG] ReferenceTransformer: "docker_registry_image.registry_image" references: []
2022-01-10T13:12:09.385+0900 [DEBUG] Starting graph walk: walkApply
2022-01-10T13:12:09.386+0900 [DEBUG] created provider logger: level=debug
2022-01-10T13:12:09.386+0900 [INFO] provider: configuring client automatic mTLS
2022-01-10T13:12:09.404+0900 [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 args=[.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0]
2022-01-10T13:12:09.407+0900 [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 pid=10413
2022-01-10T13:12:09.407+0900 [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0
2022-01-10T13:12:09.431+0900 [INFO] provider.terraform-provider-docker_v2.15.0: configuring server automatic mTLS: timestamp=2022-01-10T13:12:09.430+0900
2022-01-10T13:12:09.459+0900 [DEBUG] provider.terraform-provider-docker_v2.15.0: plugin address: address=/var/folders/h5/cfl1y25d5f15br56gpm06fdw0000gn/T/plugin039443752 network=unix timestamp=2022-01-10T13:12:09.459+0900
2022-01-10T13:12:09.460+0900 [DEBUG] provider: using plugin: version=5
2022-01-10T13:12:09.483+0900 [WARN] ValidateProviderConfig from "provider[\"registry.terraform.io/kreuzwerker/docker\"]" changed the config value, but that value is unused
2022-01-10T13:12:09.493+0900 [INFO] provider.terraform-provider-docker_v2.15.0: 2022/01/10 13:12:09 [DEBUG] Using username for registry auths: mazrean: timestamp=2022-01-10T13:12:09.493+0900
2022-01-10T13:12:09.534+0900 [WARN] Provider "registry.terraform.io/kreuzwerker/docker" produced an invalid plan for docker_registry_image.registry_image, 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:
- .insecure_skip_verify: planned value cty.False for a non-computed attribute
- .keep_remotely: planned value cty.False for a non-computed attribute
- .build[0].context: planned value cty.StringVal("/Users/mazrean/programs/teraform/ghcr-sample:17ee4784eac8734686818753f4c5851ace8cd387070f99144fc82ae34c295f0c") does not match config value cty.StringVal("/Users/mazrean/programs/teraform/ghcr-sample")
- .build[0].dockerfile: planned value cty.StringVal("Dockerfile") for a non-computed attribute
docker_registry_image.registry_image: Creating...
2022-01-10T13:12:09.535+0900 [INFO] Starting apply for docker_registry_image.registry_image
2022-01-10T13:12:09.535+0900 [DEBUG] docker_registry_image.registry_image: applying the planned Create change
2022-01-10T13:12:09.536+0900 [INFO] provider.terraform-provider-docker_v2.15.0: 2022/01/10 13:12:09 [DEBUG] Creating docker image ghcr.io/mazrean/helloworld:1.0: timestamp=2022-01-10T13:12:09.536+0900
2022-01-10T13:12:09.536+0900 [INFO] provider.terraform-provider-docker_v2.15.0: 2022/01/10 13:12:09 [DEBUG] Building docker image: timestamp=2022-01-10T13:12:09.536+0900
docker_registry_image.registry_image: Still creating... [10s elapsed]
2022-01-10T13:12:24.196+0900 [INFO] provider.terraform-provider-docker_v2.15.0: 2022/01/10 13:12:24 [DEBUG] Pushed image: ghcr.io/mazrean/helloworld:1.0: timestamp=2022-01-10T13:12:24.195+0900
docker_registry_image.registry_image: Still creating... [20s elapsed]
2022-01-10T13:12:35.075+0900 [ERROR] vertex "docker_registry_image.registry_image" error: Unable to create image, image not found: unable to get digest: Got bad response from registry: 403 Forbidden
╷
│ Error: Unable to create image, image not found: unable to get digest: Got bad response from registry: 403 Forbidden
│
│ with docker_registry_image.registry_image,
│ on main.tf line 20, in resource "docker_registry_image" "registry_image":
│ 20: resource "docker_registry_image" "registry_image" {
│
╵
2022-01-10T13:12:35.101+0900 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2022-01-10T13:12:35.104+0900 [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/kreuzwerker/docker/2.15.0/darwin_arm64/terraform-provider-docker_v2.15.0 pid=10413
2022-01-10T13:12:35.104+0900 [DEBUG] provider: plugin exited
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment