dacbd/action.yml

2021-09-22T18:48:13.6825399Z ##[group]Run cml-runner \
2021-09-22T18:48:13.6826084Z �[36;1mcml-runner \�[0m
2021-09-22T18:48:13.6826657Z �[36;1m  --log=debug \�[0m
2021-09-22T18:48:13.6827188Z �[36;1m  --single \�[0m
2021-09-22T18:48:13.6827767Z �[36;1m  --idle-timeout=1800 \�[0m
2021-09-22T18:48:13.6828911Z �[36;1m  --token=*** \�[0m
2021-09-22T18:48:13.6829583Z �[36;1m  --cloud=gcp \�[0m
2021-09-22T18:48:13.6830204Z �[36;1m  --cloud-region=us-west \�[0m
2021-09-22T18:48:13.6830856Z �[36;1m  --cloud-type=m \�[0m
2021-09-22T18:48:13.6831472Z �[36;1m  --cloud-gpu=nogpu \�[0m
2021-09-22T18:48:13.6832114Z �[36;1m  --cloud-hdd-size=10�[0m
2021-09-22T18:48:13.6879996Z shell: /usr/bin/bash -e ***0***
2021-09-22T18:48:13.6880512Z env:
2021-09-22T18:48:13.6905337Z   GOOGLE_APPLICATION_CREDENTIALS_DATA: ***
2021-09-22T18:48:13.6905955Z ##[endgroup]
2021-09-22T18:48:13.6949453Z ##[debug]/usr/bin/bash -e /home/runner/work/_temp/fe3c1be5-e5ad-436a-8b3a-ece8934680f8.sh
2021-09-22T18:48:15.3337533Z ***"level":"info","message":"Preparing workdir /home/runner/.cml/cml-0lb6umdjde..."***
2021-09-22T18:48:15.3350256Z ***"level":"info","message":"Deploying cloud runner plan..."***
2021-09-22T18:48:15.3353486Z ***"level":"info","message":"Terraform apply..."***
2021-09-22T18:48:24.1779408Z ***"level":"error"***
2021-09-22T18:48:24.1781609Z ***"level":"error","status":"terminated"***
2021-09-22T18:48:44.2034492Z ##[error]Process completed with exit code 1.

dacbd · 2021-09-22T19:13:07Z

Image for the prettiness 🌷

0x2b3bfa0 · 2021-09-22T19:22:41Z

@danielbarnes, can you please run TF_LOG=debug TF_LOG_PATH=/tmp/terraform.log cml-runner ••• || cat /tmp/terraform.log and post the output?

Update: it's PATH instead of FILE as I wrote originally. 🤦

dacbd · 2021-09-22T19:24:01Z

I have noticed in my testing that with 0.7.1 it seems to be actually terminating instances and with 0.7.0 it would fail iirc

dacbd · 2021-09-22T19:36:32Z

2021-09-22T19:30:46.5438726Z ##[debug]Starting: Deploy runner on GCP
2021-09-22T19:30:46.5455251Z ##[debug]Loading inputs
2021-09-22T19:30:46.5467048Z ##[debug]Evaluating: format('touch /tmp/terraform.log
2021-09-22T19:30:46.5468152Z ##[debug]cml-runner \
2021-09-22T19:30:46.5468809Z ##[debug]  --log=debug \
2021-09-22T19:30:46.5469449Z ##[debug]  --single \
2021-09-22T19:30:46.5470126Z ##[debug]  --idle-timeout=1800 \
2021-09-22T19:30:46.5470919Z ##[debug]  --token=***0*** \
2021-09-22T19:30:46.5471557Z ##[debug]  --cloud=gcp \
2021-09-22T19:30:46.5472268Z ##[debug]  --cloud-region=us-west \
2021-09-22T19:30:46.5473006Z ##[debug]  --cloud-type=m \
2021-09-22T19:30:46.5473704Z ##[debug]  --cloud-gpu=nogpu \
2021-09-22T19:30:46.5474558Z ##[debug]  --cloud-hdd-size=10 || cat /tmp/terraform.log
2021-09-22T19:30:46.5475423Z ##[debug]', secrets.PAT_DCB)
2021-09-22T19:30:46.5476163Z ##[debug]Evaluating format:
2021-09-22T19:30:46.5498362Z ##[debug]..Evaluating String:
2021-09-22T19:30:46.5499236Z ##[debug]..=> 'touch /tmp/terraform.log
2021-09-22T19:30:46.5499989Z ##[debug]cml-runner \
2021-09-22T19:30:46.5500641Z ##[debug]  --log=debug \
2021-09-22T19:30:46.5501273Z ##[debug]  --single \
2021-09-22T19:30:46.5501964Z ##[debug]  --idle-timeout=1800 \
2021-09-22T19:30:46.5502755Z ##[debug]  --token=***0*** \
2021-09-22T19:30:46.5503399Z ##[debug]  --cloud=gcp \
2021-09-22T19:30:46.5504106Z ##[debug]  --cloud-region=us-west \
2021-09-22T19:30:46.5504846Z ##[debug]  --cloud-type=m \
2021-09-22T19:30:46.5505556Z ##[debug]  --cloud-gpu=nogpu \
2021-09-22T19:30:46.5506423Z ##[debug]  --cloud-hdd-size=10 || cat /tmp/terraform.log
2021-09-22T19:30:46.5507200Z ##[debug]'
2021-09-22T19:30:46.5532853Z ##[debug]..Evaluating Index:
2021-09-22T19:30:46.5533638Z ##[debug]....Evaluating secrets:
2021-09-22T19:30:46.5534376Z ##[debug]....=> Object
2021-09-22T19:30:46.5535074Z ##[debug]....Evaluating String:
2021-09-22T19:30:46.5535775Z ##[debug]....=> 'PAT_DCB'
2021-09-22T19:30:46.5537153Z ##[debug]..=> '***'
2021-09-22T19:30:46.5539084Z ##[debug]=> 'touch /tmp/terraform.log
2021-09-22T19:30:46.5539832Z ##[debug]cml-runner \
2021-09-22T19:30:46.5540480Z ##[debug]  --log=debug \
2021-09-22T19:30:46.5541116Z ##[debug]  --single \
2021-09-22T19:30:46.5541994Z ##[debug]  --idle-timeout=1800 \
2021-09-22T19:30:46.5543350Z ##[debug]  --token=*** \
2021-09-22T19:30:46.5544260Z ##[debug]  --cloud=gcp \
2021-09-22T19:30:46.5544976Z ##[debug]  --cloud-region=us-west \
2021-09-22T19:30:46.5545716Z ##[debug]  --cloud-type=m \
2021-09-22T19:30:46.5546421Z ##[debug]  --cloud-gpu=nogpu \
2021-09-22T19:30:46.5547283Z ##[debug]  --cloud-hdd-size=10 || cat /tmp/terraform.log
2021-09-22T19:30:46.5548295Z ##[debug]'
2021-09-22T19:30:46.5549153Z ##[debug]Result: 'touch /tmp/terraform.log
2021-09-22T19:30:46.5549910Z ##[debug]cml-runner \
2021-09-22T19:30:46.5550557Z ##[debug]  --log=debug \
2021-09-22T19:30:46.5551192Z ##[debug]  --single \
2021-09-22T19:30:46.5551858Z ##[debug]  --idle-timeout=1800 \
2021-09-22T19:30:46.5553069Z ##[debug]  --token=*** \
2021-09-22T19:30:46.5553699Z ##[debug]  --cloud=gcp \
2021-09-22T19:30:46.5554401Z ##[debug]  --cloud-region=us-west \
2021-09-22T19:30:46.5555130Z ##[debug]  --cloud-type=m \
2021-09-22T19:30:46.5555826Z ##[debug]  --cloud-gpu=nogpu \
2021-09-22T19:30:46.5556681Z ##[debug]  --cloud-hdd-size=10 || cat /tmp/terraform.log
2021-09-22T19:30:46.5557455Z ##[debug]'
2021-09-22T19:30:46.5566386Z ##[debug]Loading env
2021-09-22T19:30:46.5584876Z ##[group]Run touch /tmp/terraform.log
2021-09-22T19:30:46.5585733Z �[36;1mtouch /tmp/terraform.log�[0m
2021-09-22T19:30:46.5586446Z �[36;1mcml-runner \�[0m
2021-09-22T19:30:46.5587311Z �[36;1m  --log=debug \�[0m
2021-09-22T19:30:46.5588044Z �[36;1m  --single \�[0m
2021-09-22T19:30:46.5588651Z �[36;1m  --idle-timeout=1800 \�[0m
2021-09-22T19:30:46.5589804Z �[36;1m  --token=*** \�[0m
2021-09-22T19:30:46.5590370Z �[36;1m  --cloud=gcp \�[0m
2021-09-22T19:30:46.5591002Z �[36;1m  --cloud-region=us-west \�[0m
2021-09-22T19:30:46.5591663Z �[36;1m  --cloud-type=m \�[0m
2021-09-22T19:30:46.5592289Z �[36;1m  --cloud-gpu=nogpu \�[0m
2021-09-22T19:30:46.5593265Z �[36;1m  --cloud-hdd-size=10 || cat /tmp/terraform.log�[0m
2021-09-22T19:30:46.5642215Z shell: /usr/bin/bash -e ***0***
2021-09-22T19:30:46.5642870Z env:
2021-09-22T19:30:46.5674392Z   GOOGLE_APPLICATION_CREDENTIALS_DATA: ***
2021-09-22T19:30:46.5675184Z   TF_LOG: debug
2021-09-22T19:30:46.5675848Z   TF_LOG_FILE: /tmp/terraform.log
2021-09-22T19:30:46.5676536Z ##[endgroup]
2021-09-22T19:30:46.5730911Z ##[debug]/usr/bin/bash -e /home/runner/work/_temp/d447d605-3ec3-4acb-be9f-5a1ee1622933.sh
2021-09-22T19:30:50.9674319Z ***"level":"info","message":"Preparing workdir /home/runner/.cml/cml-rzltlzv2me..."***
2021-09-22T19:30:50.9685261Z ***"level":"info","message":"Deploying cloud runner plan..."***
2021-09-22T19:30:50.9691480Z ***"level":"info","message":"Terraform apply..."***
2021-09-22T19:31:03.2746530Z ***"level":"error"***
2021-09-22T19:31:03.2749842Z ***"level":"error","status":"terminated"***
2021-09-22T19:31:23.3039297Z ##[debug]Finishing: Deploy runner on GCP

Image

:

dacbd · 2021-09-22T19:43:59Z

@0x2b3bfa0 I think I'm going to generate a new PAT to test this manually instead of from in the github action.

dacbd · 2021-09-22T21:00:04Z

action yaml:

  deploy-runner:
    runs-on: ubuntu-latest
    steps:
      - uses: DanielBarnes/setup-cml@2970b7282efdade5a071a7be526162a4edcb149b
      - uses: actions/checkout@v2
      - name: Deploy runner on GCP # https://cml.dev/doc/self-hosted-runners#cloud-compute-resource-credentials
        env:
          GOOGLE_APPLICATION_CREDENTIALS_DATA: ${{ secrets.GCP_CML_RUNNER_KEY }}
        run: |
          touch /tmp/terraform.log
          TF_LOG=DEBUG TF_LOG_PATH=/tmp/terraform.log cml-runner \
            --single \
            --idle-timeout=1800 \
            --token=${{ secrets.PAT_DCB }} \
            --cloud=gcp \
            --cloud-region=us-west \
            --cloud-type=m \
            --cloud-gpu=nogpu \
            --cloud-hdd-size=10 & tail -f /tmp/terraform.log

Log output:

##[debug]Evaluating: secrets.GCP_CML_RUNNER_KEY
##[debug]Evaluating Index:
##[debug]..Evaluating secrets:
##[debug]..=> Object
##[debug]..Evaluating String:
##[debug]..=> 'GCP_CML_RUNNER_KEY'
##[debug]=> '***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]***'
##[debug]Result: '***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]***'
##[debug]Evaluating condition for step: 'Deploy runner on GCP'
##[debug]Evaluating: success()
##[debug]Evaluating success:
##[debug]=> true
##[debug]Result: true
##[debug]Starting: Deploy runner on GCP
##[debug]Loading inputs
##[debug]Evaluating: format('touch /tmp/terraform.log
##[debug]TF_LOG=DEBUG TF_LOG_PATH=/tmp/terraform.log cml-runner \
##[debug]  --single \
##[debug]  --idle-timeout=1800 \
##[debug]  --token=***0*** \
##[debug]  --cloud=gcp \
##[debug]  --cloud-region=us-west \
##[debug]  --cloud-type=m \
##[debug]  --cloud-gpu=nogpu \
##[debug]  --cloud-hdd-size=10 & tail -f /tmp/terraform.log
##[debug]', secrets.PAT_DCB)
##[debug]Evaluating format:
##[debug]..Evaluating String:
##[debug]..=> 'touch /tmp/terraform.log
##[debug]TF_LOG=DEBUG TF_LOG_PATH=/tmp/terraform.log cml-runner \
##[debug]  --single \
##[debug]  --idle-timeout=1800 \
##[debug]  --token=***0*** \
##[debug]  --cloud=gcp \
##[debug]  --cloud-region=us-west \
##[debug]  --cloud-type=m \
##[debug]  --cloud-gpu=nogpu \
##[debug]  --cloud-hdd-size=10 & tail -f /tmp/terraform.log
##[debug]'
##[debug]..Evaluating Index:
##[debug]....Evaluating secrets:
##[debug]....=> Object
##[debug]....Evaluating String:
##[debug]....=> 'PAT_DCB'
##[debug]..=> '***'
##[debug]=> 'touch /tmp/terraform.log
##[debug]TF_LOG=DEBUG TF_LOG_PATH=/tmp/terraform.log cml-runner \
##[debug]  --single \
##[debug]  --idle-timeout=1800 \
##[debug]  --token=*** \
##[debug]  --cloud=gcp \
##[debug]  --cloud-region=us-west \
##[debug]  --cloud-type=m \
##[debug]  --cloud-gpu=nogpu \
##[debug]  --cloud-hdd-size=10 & tail -f /tmp/terraform.log
##[debug]'
##[debug]Result: 'touch /tmp/terraform.log
##[debug]TF_LOG=DEBUG TF_LOG_PATH=/tmp/terraform.log cml-runner \
##[debug]  --single \
##[debug]  --idle-timeout=1800 \
##[debug]  --token=*** \
##[debug]  --cloud=gcp \
##[debug]  --cloud-region=us-west \
##[debug]  --cloud-type=m \
##[debug]  --cloud-gpu=nogpu \
##[debug]  --cloud-hdd-size=10 & tail -f /tmp/terraform.log
##[debug]'
##[debug]Loading env
Run touch /tmp/terraform.log
##[debug]/usr/bin/bash -e /home/runner/work/_temp/e062b06b-aea9-4764-86b9-cd9e7d80336c.sh
2021-09-22T20:45:13.280Z [DEBUG] Adding temp file log sink: /tmp/terraform-log697670667
2021-09-22T20:45:13.280Z [INFO]  Terraform version: 1.0.6
2021-09-22T20:45:13.280Z [INFO]  Go runtime version: go1.16.4
2021-09-22T20:45:13.280Z [INFO]  CLI args: []string***"/usr/local/bin/terraform", "version", "-json"***
2021-09-22T20:45:13.280Z [DEBUG] Attempting to open CLI config file: /home/runner/.terraformrc
2021-09-22T20:45:13.280Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T20:45:13.280Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T20:45:13.280Z [DEBUG] ignoring non-existing provider search directory /home/runner/.terraform.d/plugins
2021-09-22T20:45:13.280Z [DEBUG] ignoring non-existing provider search directory /home/runner/.local/share/terraform/plugins
2021-09-22T20:45:13.280Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T20:45:13.280Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T20:45:13.287Z [INFO]  CLI command args: []string***"version", "-json"***
***"level":"info","message":"Preparing workdir /home/runner/.cml/cml-6hmak6f62b..."***
***"level":"info","message":"Deploying cloud runner plan..."***
***"level":"info","message":"Terraform apply..."***
2021-09-22T20:45:13.929Z [DEBUG] Adding temp file log sink: /tmp/terraform-log666440666
2021-09-22T20:45:13.929Z [INFO]  Terraform version: 1.0.6
2021-09-22T20:45:13.929Z [INFO]  Go runtime version: go1.16.4
2021-09-22T20:45:13.929Z [INFO]  CLI args: []string***"/usr/local/bin/terraform", "-chdir=/home/runner/.cml/cml-6hmak6f62b", "init"***
2021-09-22T20:45:13.929Z [DEBUG] Attempting to open CLI config file: /home/runner/.terraformrc
2021-09-22T20:45:13.929Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T20:45:13.930Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T20:45:13.930Z [DEBUG] ignoring non-existing provider search directory /home/runner/.terraform.d/plugins
2021-09-22T20:45:13.930Z [DEBUG] ignoring non-existing provider search directory /home/runner/.local/share/terraform/plugins
2021-09-22T20:45:13.930Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T20:45:13.930Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T20:45:13.934Z [INFO]  CLI command args: []string***"init"***
2021-09-22T20:45:13.975Z [DEBUG] New state was assigned lineage "e464683b-27c3-a908-47f2-c3118a8ff32f"
2021-09-22T20:45:13.975Z [DEBUG] checking for provisioner in "."
2021-09-22T20:45:13.976Z [DEBUG] checking for provisioner in "/usr/local/bin"
2021-09-22T20:45:13.976Z [INFO]  Failed to read plugin lock file .terraform/plugins/linux_amd64/lock.json: open .terraform/plugins/linux_amd64/lock.json: no such file or directory
2021-09-22T20:45:13.976Z [DEBUG] Service discovery for registry.terraform.io at https://registry.terraform.io/.well-known/terraform.json
2021-09-22T20:45:14.035Z [DEBUG] GET https://registry.terraform.io/v1/providers/iterative/iterative/versions
2021-09-22T20:45:14.083Z [DEBUG] GET https://registry.terraform.io/v1/providers/iterative/iterative/0.7.3/download/linux/amd64
2021-09-22T20:45:14.115Z [DEBUG] GET https://github.com/iterative/terraform-provider-iterative/releases/download/v0.7.3/terraform-provider-iterative_0.7.3_SHA256SUMS
2021-09-22T20:45:14.245Z [DEBUG] GET https://github.com/iterative/terraform-provider-iterative/releases/download/v0.7.3/terraform-provider-iterative_0.7.3_SHA256SUMS.sig
2021-09-22T20:45:15.020Z [DEBUG] Provider signed by 910A9EF2910B1E37 Iterative (Terraform provider key) <eng@iterative.ai>
2021-09-22T20:45:15.529Z [DEBUG] Adding temp file log sink: /tmp/terraform-log212625359
2021-09-22T20:45:15.529Z [INFO]  Terraform version: 1.0.6
2021-09-22T20:45:15.529Z [INFO]  Go runtime version: go1.16.4
2021-09-22T20:45:15.529Z [INFO]  CLI args: []string***"/usr/local/bin/terraform", "-chdir=/home/runner/.cml/cml-6hmak6f62b", "apply", "-auto-approve"***
2021-09-22T20:45:15.529Z [DEBUG] Attempting to open CLI config file: /home/runner/.terraformrc
2021-09-22T20:45:15.529Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T20:45:15.530Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T20:45:15.530Z [DEBUG] ignoring non-existing provider search directory /home/runner/.terraform.d/plugins
2021-09-22T20:45:15.530Z [DEBUG] ignoring non-existing provider search directory /home/runner/.local/share/terraform/plugins
2021-09-22T20:45:15.530Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T20:45:15.530Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T20:45:15.531Z [INFO]  CLI command args: []string***"apply", "-auto-approve"***
2021-09-22T20:45:15.534Z [DEBUG] New state was assigned lineage "d8301152-a8af-9bba-92e0-1d87752fb8a0"
2021-09-22T20:45:15.652Z [DEBUG] checking for provisioner in "."
2021-09-22T20:45:15.652Z [DEBUG] checking for provisioner in "/usr/local/bin"
2021-09-22T20:45:15.653Z [INFO]  Failed to read plugin lock file .terraform/plugins/linux_amd64/lock.json: open .terraform/plugins/linux_amd64/lock.json: no such file or directory
2021-09-22T20:45:15.653Z [INFO]  backend/local: starting Apply operation
2021-09-22T20:45:15.665Z [DEBUG] created provider logger: level=debug
2021-09-22T20:45:15.665Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T20:45:15.703Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T20:45:15.704Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2498
2021-09-22T20:45:15.704Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T20:45:15.721Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T20:45:15.721Z
2021-09-22T20:45:15.773Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin306532034 network=unix timestamp=2021-09-22T20:45:15.773Z
2021-09-22T20:45:15.773Z [DEBUG] provider: using plugin: version=5
2021-09-22T20:45:15.843Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T20:45:15.845Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2498
2021-09-22T20:45:15.845Z [DEBUG] provider: plugin exited
2021-09-22T20:45:15.850Z [INFO]  terraform: building graph: GraphTypeValidate
2021-09-22T20:45:15.859Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T20:45:15.859Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T20:45:15.859Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T20:45:15.860Z [DEBUG] Starting graph walk: walkValidate
2021-09-22T20:45:15.860Z [DEBUG] created provider logger: level=debug
2021-09-22T20:45:15.860Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T20:45:15.899Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T20:45:15.899Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2505
2021-09-22T20:45:15.899Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T20:45:15.915Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T20:45:15.915Z
2021-09-22T20:45:15.953Z [DEBUG] provider: using plugin: version=5
2021-09-22T20:45:15.953Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: network=unix address=/tmp/plugin328793696 timestamp=2021-09-22T20:45:15.952Z
2021-09-22T20:45:16.004Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T20:45:16.006Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2505
2021-09-22T20:45:16.006Z [DEBUG] provider: plugin exited
2021-09-22T20:45:16.006Z [INFO]  backend/local: apply calling Plan
2021-09-22T20:45:16.006Z [INFO]  terraform: building graph: GraphTypePlan
2021-09-22T20:45:16.006Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T20:45:16.006Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner (expand)" references: []
2021-09-22T20:45:16.006Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T20:45:16.007Z [DEBUG] Starting graph walk: walkPlan
2021-09-22T20:45:16.007Z [DEBUG] created provider logger: level=debug
2021-09-22T20:45:16.007Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T20:45:16.045Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T20:45:16.045Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2513
2021-09-22T20:45:16.045Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T20:45:16.062Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T20:45:16.062Z
2021-09-22T20:45:16.102Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin259273376 network=unix timestamp=2021-09-22T20:45:16.101Z
2021-09-22T20:45:16.102Z [DEBUG] provider: using plugin: version=5
2021-09-22T20:45:16.151Z [DEBUG] Resource instance state not found for node "iterative_cml_runner.runner", instance iterative_cml_runner.runner
2021-09-22T20:45:16.152Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T20:45:16.159Z [DEBUG] refresh: iterative_cml_runner.runner: no state, so not refreshing
2021-09-22T20:45:16.162Z [WARN]  Provider "registry.terraform.io/iterative/iterative" produced an invalid plan for iterative_cml_runner.runner, 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:
      - .spot: planned value cty.False for a non-computed attribute
2021-09-22T20:45:16.170Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T20:45:16.170Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2513
2021-09-22T20:45:16.170Z [DEBUG] provider: plugin exited
2021-09-22T20:45:16.172Z [INFO]  backend/local: apply calling Apply
2021-09-22T20:45:16.172Z [INFO]  terraform: building graph: GraphTypeApply
2021-09-22T20:45:16.172Z [DEBUG] Resource state not found for node "iterative_cml_runner.runner", instance iterative_cml_runner.runner
2021-09-22T20:45:16.172Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner" (*terraform.NodeApplyableResourceInstance) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T20:45:16.172Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandApplyableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T20:45:16.172Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T20:45:16.172Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner (expand)" references: []
2021-09-22T20:45:16.172Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T20:45:16.172Z [DEBUG] Starting graph walk: walkApply
2021-09-22T20:45:16.173Z [DEBUG] created provider logger: level=debug
2021-09-22T20:45:16.173Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T20:45:16.211Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T20:45:16.211Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2521
2021-09-22T20:45:16.211Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T20:45:16.228Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T20:45:16.228Z
2021-09-22T20:45:16.267Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: network=unix address=/tmp/plugin742755573 timestamp=2021-09-22T20:45:16.267Z
2021-09-22T20:45:16.267Z [DEBUG] provider: using plugin: version=5
2021-09-22T20:45:16.324Z [WARN]  Provider "registry.terraform.io/iterative/iterative" produced an invalid plan for iterative_cml_runner.runner, 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:
      - .spot: planned value cty.False for a non-computed attribute
2021-09-22T20:45:16.328Z [INFO]  Starting apply for iterative_cml_runner.runner
2021-09-22T20:45:16.328Z [DEBUG] iterative_cml_runner.runner: applying the planned Create change
2021-09-22T20:45:18.899Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T20:45:18.901Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2521
2021-09-22T20:45:18.901Z [DEBUG] provider: plugin exited
***"level":"error"***
***"level":"error","status":"terminated"***
##[debug]Re-evaluate condition on job cancellation for step: 'Deploy runner on GCP'.
Error: The operation was canceled.
##[debug]System.OperationCanceledException: The operation was canceled.
##[debug]   at System.Threading.CancellationToken.ThrowOperationCanceledException()
##[debug]   at GitHub.Runner.Sdk.ProcessInvoker.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, Channel`1 redirectStandardIn, Boolean inheritConsoleHandler, Boolean keepStandardInOpen, Boolean highPriorityProcess, CancellationToken cancellationToken)
##[debug]   at GitHub.Runner.Common.ProcessInvokerWrapper.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, Channel`1 redirectStandardIn, Boolean inheritConsoleHandler, Boolean keepStandardInOpen, Boolean highPriorityProcess, CancellationToken cancellationToken)
##[debug]   at GitHub.Runner.Worker.Handlers.DefaultStepHost.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, Boolean inheritConsoleHandler, CancellationToken cancellationToken)
##[debug]   at GitHub.Runner.Worker.Handlers.ScriptHandler.RunAsync(ActionRunStage stage)
##[debug]   at GitHub.Runner.Worker.ActionRunner.RunAsync()
##[debug]   at GitHub.Runner.Worker.StepsRunner.RunStepAsync(IStep step, CancellationToken jobCancellationToken)
##[debug]Finishing: Deploy runner on GCP

dacbd · 2021-09-22T21:47:11Z

SUCCESSFUL LOCAL RUN LOG:

root@83cdbfd70274:/home/runner# TF_LOG=TRACE TF_LOG_PATH=/tmp/terraform.log cml-runner \
>     --single \
>     --log=debug \
>     --idle-timeout=1800 \
>     --token=[REDACTED] \
>     --cloud=gcp \
>     --cloud-region=us-west \
>     --cloud-type=m \
>     --cloud-gpu=nogpu \
>     --repo=[REDACTED] \
>     --driver=github \
>     --cloud-hdd-size=10 & tail -f /tmp/terraform.log
[1] 557
2021-09-22T20:30:44.979Z [TRACE] dag/walk: upstream of "provider[\"registry.terraform.io/iterative/iterative\"] (close)" errored, so skipping
2021-09-22T20:30:44.979Z [TRACE] dag/walk: upstream of "root" errored, so skipping
2021-09-22T20:30:44.979Z [TRACE] backend/local: graceful stop has completed
2021-09-22T20:30:44.979Z [WARN]  terraform: stop complete
2021-09-22T20:30:44.979Z [TRACE] statemgr.Filesystem: not making a backup, because the new snapshot is identical to the old
2021-09-22T20:30:44.979Z [TRACE] statemgr.Filesystem: no state changes since last snapshot
2021-09-22T20:30:44.979Z [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2021-09-22T20:30:44.981Z [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2021-09-22T20:30:44.981Z [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
2021-09-22T20:30:44.981Z [DEBUG] provider: plugin exited
2021-09-22T21:08:26.992Z [DEBUG] Adding temp file log sink: /tmp/terraform-log661723524
2021-09-22T21:08:26.992Z [INFO]  Terraform version: 1.0.7
2021-09-22T21:08:26.992Z [INFO]  Go runtime version: go1.16.4
2021-09-22T21:08:26.992Z [INFO]  CLI args: []string{"/usr/bin/terraform", "version", "-json"}
2021-09-22T21:08:26.992Z [TRACE] Stdout is not a terminal
2021-09-22T21:08:26.992Z [TRACE] Stderr is not a terminal
2021-09-22T21:08:26.992Z [TRACE] Stdin is not a terminal
2021-09-22T21:08:26.992Z [DEBUG] Attempting to open CLI config file: /root/.terraformrc
2021-09-22T21:08:26.992Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T21:08:26.993Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T21:08:26.993Z [DEBUG] ignoring non-existing provider search directory /root/.terraform.d/plugins
2021-09-22T21:08:26.993Z [DEBUG] ignoring non-existing provider search directory /root/.local/share/terraform/plugins
2021-09-22T21:08:26.993Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T21:08:26.993Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T21:08:26.995Z [INFO]  CLI command args: []string{"version", "-json"}
info: Preparing workdir /home/runner...
info: Deploying cloud runner plan...
info: Terraform apply...
2021-09-22T21:08:28.449Z [DEBUG] Adding temp file log sink: /tmp/terraform-log945699559
2021-09-22T21:08:28.449Z [INFO]  Terraform version: 1.0.7
2021-09-22T21:08:28.449Z [INFO]  Go runtime version: go1.16.4
2021-09-22T21:08:28.449Z [INFO]  CLI args: []string{"/usr/bin/terraform", "-chdir=/home/runner", "init"}
2021-09-22T21:08:28.449Z [TRACE] Stdout is not a terminal
2021-09-22T21:08:28.449Z [TRACE] Stderr is not a terminal
2021-09-22T21:08:28.449Z [TRACE] Stdin is not a terminal
2021-09-22T21:08:28.449Z [DEBUG] Attempting to open CLI config file: /root/.terraformrc
2021-09-22T21:08:28.449Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T21:08:28.449Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T21:08:28.449Z [DEBUG] ignoring non-existing provider search directory /root/.terraform.d/plugins
2021-09-22T21:08:28.450Z [DEBUG] ignoring non-existing provider search directory /root/.local/share/terraform/plugins
2021-09-22T21:08:28.450Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T21:08:28.450Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T21:08:28.458Z [INFO]  CLI command args: []string{"init"}
2021-09-22T21:08:28.465Z [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2021-09-22T21:08:28.465Z [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2021-09-22T21:08:28.465Z [DEBUG] New state was assigned lineage "38e16b77-c177-28fe-ad66-2dcad24cf097"
2021-09-22T21:08:28.465Z [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2021-09-22T21:08:28.465Z [TRACE] Meta.Backend: instantiated backend of type <nil>
2021-09-22T21:08:28.466Z [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2021-09-22T21:08:28.467Z [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/iterative/iterative v0.7.3 for linux_amd64 at .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64
2021-09-22T21:08:28.467Z [TRACE] providercache.fillMetaCache: including .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64 as a candidate package for registry.terraform.io/iterative/iterative 0.7.3
2021-09-22T21:08:28.911Z [DEBUG] checking for provisioner in "."
2021-09-22T21:08:28.918Z [DEBUG] checking for provisioner in "/usr/bin"
2021-09-22T21:08:28.921Z [INFO]  Failed to read plugin lock file .terraform/plugins/linux_amd64/lock.json: open .terraform/plugins/linux_amd64/lock.json: no such file or directory
2021-09-22T21:08:28.921Z [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2021-09-22T21:08:28.922Z [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
2021-09-22T21:08:28.922Z [TRACE] statemgr.Filesystem: reading initial snapshot from terraform.tfstate
2021-09-22T21:08:28.922Z [TRACE] statemgr.Filesystem: read snapshot with lineage "896377c9-e50f-d35b-6972-8e4597650836" serial 7
2021-09-22T21:08:28.924Z [DEBUG] Service discovery for registry.terraform.io at https://registry.terraform.io/.well-known/terraform.json
2021-09-22T21:08:28.924Z [TRACE] HTTP client GET request to https://registry.terraform.io/.well-known/terraform.json
2021-09-22T21:08:29.180Z [DEBUG] GET https://registry.terraform.io/v1/providers/iterative/iterative/versions
2021-09-22T21:08:29.180Z [TRACE] HTTP client GET request to https://registry.terraform.io/v1/providers/iterative/iterative/versions
2021-09-22T21:08:29.413Z [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2021-09-22T21:08:29.414Z [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/iterative/iterative v0.7.3 for linux_amd64 at .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64
2021-09-22T21:08:29.414Z [TRACE] providercache.fillMetaCache: including .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64 as a candidate package for registry.terraform.io/iterative/iterative 0.7.3
2021-09-22T21:08:30.144Z [DEBUG] Adding temp file log sink: /tmp/terraform-log320011176
2021-09-22T21:08:30.144Z [INFO]  Terraform version: 1.0.7
2021-09-22T21:08:30.144Z [INFO]  Go runtime version: go1.16.4
2021-09-22T21:08:30.144Z [INFO]  CLI args: []string{"/usr/bin/terraform", "-chdir=/home/runner", "apply", "-auto-approve"}
2021-09-22T21:08:30.144Z [TRACE] Stdout is not a terminal
2021-09-22T21:08:30.144Z [TRACE] Stderr is not a terminal
2021-09-22T21:08:30.144Z [TRACE] Stdin is not a terminal
2021-09-22T21:08:30.144Z [DEBUG] Attempting to open CLI config file: /root/.terraformrc
2021-09-22T21:08:30.144Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T21:08:30.144Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T21:08:30.144Z [DEBUG] ignoring non-existing provider search directory /root/.terraform.d/plugins
2021-09-22T21:08:30.144Z [DEBUG] ignoring non-existing provider search directory /root/.local/share/terraform/plugins
2021-09-22T21:08:30.144Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T21:08:30.144Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T21:08:30.146Z [INFO]  CLI command args: []string{"apply", "-auto-approve"}
2021-09-22T21:08:30.148Z [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2021-09-22T21:08:30.148Z [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2021-09-22T21:08:30.148Z [DEBUG] New state was assigned lineage "6abc34cd-35f5-8566-bc2e-3b8fb9a6ad11"
2021-09-22T21:08:30.148Z [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2021-09-22T21:08:30.148Z [TRACE] Meta.Backend: instantiated backend of type <nil>
2021-09-22T21:08:30.149Z [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2021-09-22T21:08:30.150Z [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/iterative/iterative v0.7.3 for linux_amd64 at .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64
2021-09-22T21:08:30.153Z [TRACE] providercache.fillMetaCache: including .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64 as a candidate package for registry.terraform.io/iterative/iterative 0.7.3
2021-09-22T21:08:30.521Z [DEBUG] checking for provisioner in "."
2021-09-22T21:08:30.529Z [DEBUG] checking for provisioner in "/usr/bin"
2021-09-22T21:08:30.530Z [INFO]  Failed to read plugin lock file .terraform/plugins/linux_amd64/lock.json: open .terraform/plugins/linux_amd64/lock.json: no such file or directory
2021-09-22T21:08:30.530Z [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2021-09-22T21:08:30.531Z [INFO]  backend/local: starting Apply operation
2021-09-22T21:08:30.531Z [TRACE] backend/local: requesting state manager for workspace "default"
2021-09-22T21:08:30.531Z [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
2021-09-22T21:08:30.531Z [TRACE] backend/local: requesting state lock for workspace "default"
2021-09-22T21:08:30.532Z [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2021-09-22T21:08:30.533Z [TRACE] statemgr.Filesystem: existing snapshot has lineage "896377c9-e50f-d35b-6972-8e4597650836" serial 7
2021-09-22T21:08:30.533Z [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2021-09-22T21:08:30.534Z [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2021-09-22T21:08:30.534Z [TRACE] backend/local: reading remote state for workspace "default"
2021-09-22T21:08:30.534Z [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2021-09-22T21:08:30.534Z [TRACE] statemgr.Filesystem: read snapshot with lineage "896377c9-e50f-d35b-6972-8e4597650836" serial 7
2021-09-22T21:08:30.534Z [TRACE] backend/local: retrieving local state snapshot for workspace "default"
2021-09-22T21:08:30.534Z [TRACE] backend/local: building context for current working directory
2021-09-22T21:08:30.535Z [TRACE] terraform.NewContext: starting
2021-09-22T21:08:30.535Z [TRACE] terraform.NewContext: loading provider schemas
2021-09-22T21:08:30.535Z [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/iterative/iterative"
2021-09-22T21:08:30.535Z [DEBUG] created provider logger: level=trace
2021-09-22T21:08:30.539Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T21:08:30.750Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T21:08:30.753Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=635
2021-09-22T21:08:30.753Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T21:08:30.824Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T21:08:30.823Z
2021-09-22T21:08:31.033Z [DEBUG] provider: using plugin: version=5
2021-09-22T21:08:31.037Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin009487228 network=unix timestamp=2021-09-22T21:08:31.032Z
2021-09-22T21:08:31.221Z [TRACE] GRPCProvider: GetProviderSchema
2021-09-22T21:08:31.221Z [TRACE] provider.stdio: waiting for stdio data
2021-09-22T21:08:31.231Z [TRACE] GRPCProvider: Close
2021-09-22T21:08:31.235Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T21:08:31.245Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=635
2021-09-22T21:08:31.245Z [DEBUG] provider: plugin exited
2021-09-22T21:08:31.246Z [TRACE] terraform.NewContext: complete
2021-09-22T21:08:31.246Z [TRACE] backend/local: finished building terraform.Context
2021-09-22T21:08:31.246Z [TRACE] backend/local: requesting interactive input, if necessary
2021-09-22T21:08:31.246Z [TRACE] Context.Input: Prompting for provider arguments
2021-09-22T21:08:31.246Z [TRACE] Context.Input: Provider provider.iterative declared at main.tf:11,1-21
2021-09-22T21:08:31.246Z [TRACE] Context.Input: Input for provider.iterative: map[string]cty.Value{}
2021-09-22T21:08:31.246Z [TRACE] backend/local: running validation operation
2021-09-22T21:08:31.246Z [INFO]  terraform: building graph: GraphTypeValidate
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.ConfigTransformer
2021-09-22T21:08:31.246Z [TRACE] ConfigTransformer: Starting for path:
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
  ------
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.LocalTransformer
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.OutputTransformer
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.StateTransformer
2021-09-22T21:08:31.246Z [TRACE] StateTransformer: state is empty, so nothing to do
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021-09-22T21:08:31.246Z [TRACE] AttachResourceConfigTransformer: attaching to "iterative_cml_runner.runner" (*terraform.NodeValidatableResource) config from main.tf:14,1-41
2021-09-22T21:08:31.246Z [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to iterative_cml_runner.runner
2021-09-22T21:08:31.246Z [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021-09-22T21:08:31.246Z [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021-09-22T21:08:31.246Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021-09-22T21:08:31.247Z [TRACE] ProviderConfigTransformer: attaching to "provider[\"registry.terraform.io/iterative/iterative\"]" provider configuration from main.tf:11,1-21
2021-09-22T21:08:31.247Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:31.247Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021-09-22T21:08:31.247Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021-09-22T21:08:31.247Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021-09-22T21:08:31.247Z [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/iterative/iterative"] serving iterative_cml_runner.runner
2021-09-22T21:08:31.247Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:31.247Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:31.247Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021-09-22T21:08:31.247Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021-09-22T21:08:31.247Z [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:31.247Z [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021-09-22T21:08:31.247Z [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021-09-22T21:08:31.247Z [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021-09-22T21:08:31.247Z [TRACE] AttachSchemaTransformer: attaching resource schema to iterative_cml_runner.runner
2021-09-22T21:08:31.247Z [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:31.247Z [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021-09-22T21:08:31.247Z [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021-09-22T21:08:31.247Z [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021-09-22T21:08:31.247Z [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021-09-22T21:08:31.248Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T21:08:31.248Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T21:08:31.248Z [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021-09-22T21:08:31.248Z [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021-09-22T21:08:31.248Z [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021-09-22T21:08:31.248Z [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2021-09-22T21:08:31.248Z [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2021-09-22T21:08:31.248Z [TRACE] Executing graph transform *terraform.TargetsTransformer
2021-09-22T21:08:31.248Z [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021-09-22T21:08:31.248Z [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021-09-22T21:08:31.248Z [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021-09-22T21:08:31.248Z [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021-09-22T21:08:31.248Z [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:31.248Z [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021-09-22T21:08:31.248Z [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:31.248Z [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021-09-22T21:08:31.248Z [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:08:31.249Z [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021-09-22T21:08:31.249Z [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:08:31.249Z [DEBUG] Starting graph walk: walkValidate
2021-09-22T21:08:31.249Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": starting visit (*terraform.NodeApplyableProvider)
2021-09-22T21:08:31.249Z [DEBUG] created provider logger: level=trace
2021-09-22T21:08:31.249Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T21:08:31.389Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T21:08:31.394Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=644
2021-09-22T21:08:31.395Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T21:08:31.493Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T21:08:31.491Z
2021-09-22T21:08:31.736Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin057487637 network=unix timestamp=2021-09-22T21:08:31.736Z
2021-09-22T21:08:31.738Z [DEBUG] provider: using plugin: version=5
2021-09-22T21:08:31.927Z [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/iterative/iterative\"]" provider for provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:31.927Z [TRACE] buildProviderConfig for provider["registry.terraform.io/iterative/iterative"]: using explicit config only
2021-09-22T21:08:31.927Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": visit complete
2021-09-22T21:08:31.933Z [TRACE] provider.stdio: waiting for stdio data
2021-09-22T21:08:31.934Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodeValidatableResource)
2021-09-22T21:08:31.937Z [TRACE] GRPCProvider: ValidateResourceConfig
2021-09-22T21:08:31.937Z [TRACE] GRPCProvider: GetProviderSchema
2021-09-22T21:08:31.982Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:08:31.982Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2021-09-22T21:08:31.982Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2021-09-22T21:08:31.982Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2021-09-22T21:08:31.982Z [TRACE] GRPCProvider: Close
2021-09-22T21:08:31.983Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T21:08:32.031Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=644
2021-09-22T21:08:32.031Z [DEBUG] provider: plugin exited
2021-09-22T21:08:32.031Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": visit complete
2021-09-22T21:08:32.031Z [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2021-09-22T21:08:32.031Z [TRACE] vertex "root": visit complete
2021-09-22T21:08:32.031Z [INFO]  backend/local: apply calling Plan
2021-09-22T21:08:32.031Z [INFO]  terraform: building graph: GraphTypePlan
2021-09-22T21:08:32.031Z [TRACE] Executing graph transform *terraform.ConfigTransformer
2021-09-22T21:08:32.031Z [TRACE] ConfigTransformer: Starting for path:
2021-09-22T21:08:32.031Z [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
  ------
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021-09-22T21:08:32.032Z [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021-09-22T21:08:32.032Z [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.LocalTransformer
2021-09-22T21:08:32.032Z [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.OutputTransformer
2021-09-22T21:08:32.032Z [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2021-09-22T21:08:32.032Z [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.StateTransformer
2021-09-22T21:08:32.032Z [TRACE] StateTransformer: state is empty, so nothing to do
2021-09-22T21:08:32.032Z [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021-09-22T21:08:32.032Z [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021-09-22T21:08:32.032Z [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021-09-22T21:08:32.032Z [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021-09-22T21:08:32.032Z [TRACE] AttachResourceConfigTransformer: attaching to "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandPlannableResource) config from main.tf:14,1-41
2021-09-22T21:08:32.035Z [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to iterative_cml_runner.runner (expand)
2021-09-22T21:08:32.035Z [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021-09-22T21:08:32.035Z [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021-09-22T21:08:32.035Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021-09-22T21:08:32.036Z [TRACE] ProviderConfigTransformer: attaching to "provider[\"registry.terraform.io/iterative/iterative\"]" provider configuration from main.tf:11,1-21
2021-09-22T21:08:32.036Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:32.037Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021-09-22T21:08:32.037Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021-09-22T21:08:32.037Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021-09-22T21:08:32.037Z [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/iterative/iterative"] serving iterative_cml_runner.runner (expand)
2021-09-22T21:08:32.038Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:32.038Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:32.038Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021-09-22T21:08:32.038Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021-09-22T21:08:32.038Z [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:32.038Z [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021-09-22T21:08:32.038Z [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021-09-22T21:08:32.038Z [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021-09-22T21:08:32.038Z [TRACE] AttachSchemaTransformer: attaching resource schema to iterative_cml_runner.runner (expand)
2021-09-22T21:08:32.038Z [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:32.038Z [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021-09-22T21:08:32.038Z [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021-09-22T21:08:32.038Z [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021-09-22T21:08:32.038Z [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021-09-22T21:08:32.038Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner (expand)" references: []
2021-09-22T21:08:32.038Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T21:08:32.038Z [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021-09-22T21:08:32.038Z [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021-09-22T21:08:32.038Z [TRACE] AttachDependenciesTransformer: iterative_cml_runner.runner depends on []
2021-09-22T21:08:32.039Z [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021-09-22T21:08:32.039Z [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2021-09-22T21:08:32.039Z [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2021-09-22T21:08:32.039Z [TRACE] Executing graph transform *terraform.TargetsTransformer
2021-09-22T21:08:32.039Z [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021-09-22T21:08:32.039Z [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021-09-22T21:08:32.039Z [TRACE] ForcedCBDTransformer: "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandPlannableResource) has no CBD descendent, so skipping
2021-09-22T21:08:32.039Z [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021-09-22T21:08:32.039Z [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021-09-22T21:08:32.039Z [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:32.039Z [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021-09-22T21:08:32.039Z [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:32.039Z [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021-09-22T21:08:32.039Z [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:08:32.039Z [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021-09-22T21:08:32.039Z [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:08:32.039Z [DEBUG] Starting graph walk: walkPlan
2021-09-22T21:08:32.040Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": starting visit (*terraform.NodeApplyableProvider)
2021-09-22T21:08:32.040Z [DEBUG] created provider logger: level=trace
2021-09-22T21:08:32.040Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T21:08:32.197Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T21:08:32.197Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=654
2021-09-22T21:08:32.197Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T21:08:32.268Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T21:08:32.267Z
2021-09-22T21:08:32.438Z [DEBUG] provider: using plugin: version=5
2021-09-22T21:08:32.439Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin210757216 network=unix timestamp=2021-09-22T21:08:32.438Z
2021-09-22T21:08:32.825Z [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/iterative/iterative\"]" provider for provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:32.825Z [TRACE] buildProviderConfig for provider["registry.terraform.io/iterative/iterative"]: using explicit config only
2021-09-22T21:08:32.825Z [TRACE] GRPCProvider: GetProviderSchema
2021-09-22T21:08:32.825Z [TRACE] provider.stdio: waiting for stdio data
2021-09-22T21:08:32.833Z [TRACE] GRPCProvider: ValidateProviderConfig
2021-09-22T21:08:32.888Z [TRACE] GRPCProvider: ConfigureProvider
2021-09-22T21:08:32.976Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": visit complete
2021-09-22T21:08:32.976Z [TRACE] vertex "iterative_cml_runner.runner (expand)": starting visit (*terraform.nodeExpandPlannableResource)
2021-09-22T21:08:32.976Z [TRACE] vertex "iterative_cml_runner.runner (expand)": expanding dynamic subgraph
2021-09-22T21:08:32.976Z [TRACE] vertex "iterative_cml_runner.runner (expand)": entering dynamic subgraph
2021-09-22T21:08:32.976Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodePlannableResource)
2021-09-22T21:08:32.976Z [TRACE] vertex "iterative_cml_runner.runner": expanding dynamic subgraph
2021-09-22T21:08:32.976Z [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2021-09-22T21:08:32.976Z [TRACE] ResourceCountTransformer: adding iterative_cml_runner.runner as *terraform.NodePlannableResourceInstance
2021-09-22T21:08:32.976Z [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodePlannableResourceInstance
  ------
2021-09-22T21:08:32.976Z [TRACE] Executing graph transform *terraform.OrphanResourceInstanceCountTransformer
2021-09-22T21:08:32.976Z [TRACE] Completed graph transform *terraform.OrphanResourceInstanceCountTransformer (no changes)
2021-09-22T21:08:32.976Z [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021-09-22T21:08:32.976Z [DEBUG] Resource instance state not found for node "iterative_cml_runner.runner", instance iterative_cml_runner.runner
2021-09-22T21:08:32.976Z [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021-09-22T21:08:32.976Z [TRACE] Executing graph transform *terraform.TargetsTransformer
2021-09-22T21:08:32.976Z [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021-09-22T21:08:32.976Z [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021-09-22T21:08:32.977Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T21:08:32.977Z [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021-09-22T21:08:32.977Z [TRACE] Executing graph transform *terraform.RootTransformer
2021-09-22T21:08:32.977Z [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2021-09-22T21:08:32.977Z [TRACE] vertex "iterative_cml_runner.runner": entering dynamic subgraph
2021-09-22T21:08:32.977Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodePlannableResourceInstance)
2021-09-22T21:08:32.977Z [TRACE] readResourceInstanceState: reading state for iterative_cml_runner.runner
2021-09-22T21:08:32.977Z [TRACE] readResourceInstanceState: no state present for iterative_cml_runner.runner
2021-09-22T21:08:32.977Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to prevRunState for iterative_cml_runner.runner
2021-09-22T21:08:32.977Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for iterative_cml_runner.runner
2021-09-22T21:08:32.977Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for iterative_cml_runner.runner
2021-09-22T21:08:32.977Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for iterative_cml_runner.runner
2021-09-22T21:08:32.977Z [TRACE] NodeAbstractResourceInstance.refresh for iterative_cml_runner.runner
2021-09-22T21:08:32.977Z [DEBUG] refresh: iterative_cml_runner.runner: no state, so not refreshing
2021-09-22T21:08:32.977Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for iterative_cml_runner.runner
2021-09-22T21:08:32.977Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for iterative_cml_runner.runner
2021-09-22T21:08:32.978Z [TRACE] Re-validating config for "iterative_cml_runner.runner"
2021-09-22T21:08:32.978Z [TRACE] GRPCProvider: ValidateResourceConfig
2021-09-22T21:08:32.983Z [TRACE] GRPCProvider: PlanResourceChange
2021-09-22T21:08:32.996Z [WARN]  Provider "registry.terraform.io/iterative/iterative" produced an invalid plan for iterative_cml_runner.runner, 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:
      - .spot: planned value cty.False for a non-computed attribute
2021-09-22T21:08:32.996Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for iterative_cml_runner.runner
2021-09-22T21:08:32.996Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for iterative_cml_runner.runner
2021-09-22T21:08:33.039Z [TRACE] writeChange: recorded Create change for iterative_cml_runner.runner
2021-09-22T21:08:33.039Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:08:33.039Z [TRACE] vertex "iterative_cml_runner.runner": dynamic subgraph completed successfully
2021-09-22T21:08:33.039Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:08:33.039Z [TRACE] vertex "iterative_cml_runner.runner (expand)": dynamic subgraph completed successfully
2021-09-22T21:08:33.039Z [TRACE] vertex "iterative_cml_runner.runner (expand)": visit complete
2021-09-22T21:08:33.039Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2021-09-22T21:08:33.039Z [TRACE] GRPCProvider: Close
2021-09-22T21:08:33.039Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2021-09-22T21:08:33.039Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2021-09-22T21:08:33.043Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T21:08:33.053Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=654
2021-09-22T21:08:33.053Z [DEBUG] provider: plugin exited
2021-09-22T21:08:33.053Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": visit complete
2021-09-22T21:08:33.053Z [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2021-09-22T21:08:33.053Z [TRACE] vertex "root": visit complete
2021-09-22T21:08:33.056Z [INFO]  backend/local: apply calling Apply
2021-09-22T21:08:33.056Z [INFO]  terraform: building graph: GraphTypeApply
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.ConfigTransformer
2021-09-22T21:08:33.056Z [TRACE] ConfigTransformer: Starting for path:
2021-09-22T21:08:33.056Z [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  ------
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021-09-22T21:08:33.056Z [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021-09-22T21:08:33.056Z [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.LocalTransformer
2021-09-22T21:08:33.056Z [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.OutputTransformer
2021-09-22T21:08:33.056Z [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.DiffTransformer
2021-09-22T21:08:33.056Z [TRACE] DiffTransformer starting
2021-09-22T21:08:33.056Z [TRACE] DiffTransformer: found Create change for iterative_cml_runner.runner
2021-09-22T21:08:33.056Z [TRACE] DiffTransformer: iterative_cml_runner.runner will be represented by iterative_cml_runner.runner
2021-09-22T21:08:33.056Z [TRACE] DiffTransformer complete
2021-09-22T21:08:33.056Z [TRACE] Completed graph transform *terraform.DiffTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  ------
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021-09-22T21:08:33.056Z [DEBUG] Resource state not found for node "iterative_cml_runner.runner", instance iterative_cml_runner.runner
2021-09-22T21:08:33.056Z [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021-09-22T21:08:33.056Z [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021-09-22T21:08:33.056Z [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021-09-22T21:08:33.057Z [TRACE] AttachResourceConfigTransformer: attaching to "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandApplyableResource) config from main.tf:14,1-41
2021-09-22T21:08:33.057Z [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to iterative_cml_runner.runner (expand)
2021-09-22T21:08:33.057Z [TRACE] AttachResourceConfigTransformer: attaching to "iterative_cml_runner.runner" (*terraform.NodeApplyableResourceInstance) config from main.tf:14,1-41
2021-09-22T21:08:33.057Z [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to iterative_cml_runner.runner
2021-09-22T21:08:33.057Z [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021-09-22T21:08:33.057Z [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021-09-22T21:08:33.057Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021-09-22T21:08:33.057Z [TRACE] ProviderConfigTransformer: attaching to "provider[\"registry.terraform.io/iterative/iterative\"]" provider configuration from main.tf:11,1-21
2021-09-22T21:08:33.057Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:33.057Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021-09-22T21:08:33.057Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021-09-22T21:08:33.057Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021-09-22T21:08:33.057Z [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/iterative/iterative"] serving iterative_cml_runner.runner (expand)
2021-09-22T21:08:33.057Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandApplyableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:33.057Z [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/iterative/iterative"] serving iterative_cml_runner.runner
2021-09-22T21:08:33.057Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner" (*terraform.NodeApplyableResourceInstance) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:33.057Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:33.057Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021-09-22T21:08:33.057Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021-09-22T21:08:33.057Z [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:33.057Z [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021-09-22T21:08:33.057Z [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021-09-22T21:08:33.057Z [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021-09-22T21:08:33.057Z [TRACE] AttachSchemaTransformer: attaching resource schema to iterative_cml_runner.runner
2021-09-22T21:08:33.057Z [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:33.058Z [TRACE] AttachSchemaTransformer: attaching resource schema to iterative_cml_runner.runner (expand)
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021-09-22T21:08:33.058Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T21:08:33.058Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner (expand)" references: []
2021-09-22T21:08:33.058Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021-09-22T21:08:33.058Z [TRACE] AttachDependenciesTransformer: iterative_cml_runner.runner depends on []
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021-09-22T21:08:33.058Z [TRACE] ForcedCBDTransformer: "iterative_cml_runner.runner" (*terraform.NodeApplyableResourceInstance) has no CBD descendent, so skipping
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.DestroyEdgeTransformer
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.DestroyEdgeTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.CBDEdgeTransformer
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.CBDEdgeTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.pruneUnusedNodesTransformer
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.pruneUnusedNodesTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.TargetsTransformer
2021-09-22T21:08:33.058Z [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021-09-22T21:08:33.058Z [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021-09-22T21:08:33.059Z [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:33.059Z [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021-09-22T21:08:33.059Z [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:08:33.059Z [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021-09-22T21:08:33.059Z [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:08:33.059Z [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021-09-22T21:08:33.059Z [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:08:33.059Z [DEBUG] Starting graph walk: walkApply
2021-09-22T21:08:33.059Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": starting visit (*terraform.NodeApplyableProvider)
2021-09-22T21:08:33.059Z [DEBUG] created provider logger: level=trace
2021-09-22T21:08:33.060Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T21:08:33.363Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T21:08:33.364Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=663
2021-09-22T21:08:33.364Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T21:08:33.468Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T21:08:33.467Z
2021-09-22T21:08:33.818Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin325008362 network=unix timestamp=2021-09-22T21:08:33.779Z
2021-09-22T21:08:33.818Z [DEBUG] provider: using plugin: version=5
2021-09-22T21:08:34.180Z [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/iterative/iterative\"]" provider for provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:08:34.180Z [TRACE] buildProviderConfig for provider["registry.terraform.io/iterative/iterative"]: using explicit config only
2021-09-22T21:08:34.183Z [TRACE] GRPCProvider: GetProviderSchema
2021-09-22T21:08:34.181Z [TRACE] provider.stdio: waiting for stdio data
2021-09-22T21:08:34.196Z [TRACE] GRPCProvider: ValidateProviderConfig
2021-09-22T21:08:34.199Z [TRACE] GRPCProvider: ConfigureProvider
2021-09-22T21:08:34.199Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": visit complete
2021-09-22T21:08:34.200Z [TRACE] vertex "iterative_cml_runner.runner (expand)": starting visit (*terraform.nodeExpandApplyableResource)
2021-09-22T21:08:34.200Z [TRACE] vertex "iterative_cml_runner.runner (expand)": expanding dynamic subgraph
2021-09-22T21:08:34.200Z [TRACE] vertex "iterative_cml_runner.runner (expand)": entering dynamic subgraph
2021-09-22T21:08:34.200Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodeApplyableResource)
2021-09-22T21:08:34.200Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:08:34.200Z [TRACE] vertex "iterative_cml_runner.runner (expand)": dynamic subgraph completed successfully
2021-09-22T21:08:34.200Z [TRACE] vertex "iterative_cml_runner.runner (expand)": visit complete
2021-09-22T21:08:34.200Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodeApplyableResourceInstance)
2021-09-22T21:08:34.200Z [TRACE] readDiff: Read Create change from plan for iterative_cml_runner.runner
2021-09-22T21:08:34.200Z [TRACE] readResourceInstanceState: reading state for iterative_cml_runner.runner
2021-09-22T21:08:34.200Z [TRACE] readResourceInstanceState: no state present for iterative_cml_runner.runner
2021-09-22T21:08:34.201Z [TRACE] readDiff: Read Create change from plan for iterative_cml_runner.runner
2021-09-22T21:08:34.201Z [TRACE] Re-validating config for "iterative_cml_runner.runner"
2021-09-22T21:08:34.201Z [TRACE] GRPCProvider: ValidateResourceConfig
2021-09-22T21:08:34.218Z [TRACE] GRPCProvider: PlanResourceChange
2021-09-22T21:08:34.223Z [WARN]  Provider "registry.terraform.io/iterative/iterative" produced an invalid plan for iterative_cml_runner.runner, 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:
      - .spot: planned value cty.False for a non-computed attribute
2021-09-22T21:08:34.223Z [TRACE] checkPlannedChange: Verifying that actual change (action Create) matches planned change (action Create)
2021-09-22T21:08:34.223Z [TRACE] readResourceInstanceState: reading state for iterative_cml_runner.runner
2021-09-22T21:08:34.224Z [TRACE] readResourceInstanceState: no state present for iterative_cml_runner.runner
2021-09-22T21:08:34.224Z [INFO]  Starting apply for iterative_cml_runner.runner
2021-09-22T21:08:34.224Z [DEBUG] iterative_cml_runner.runner: applying the planned Create change
2021-09-22T21:08:34.225Z [TRACE] GRPCProvider: ApplyResourceChange
2021-09-22T21:08:38.060Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:38.060Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:38.060Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:08:39.193Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:39 [DEBUG] Waiting for state to become: [success]: timestamp=2021-09-22T21:08:39.193Z
2021-09-22T21:08:39.430Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:39 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x165f477ebfe625b9, InsertTime:"2021-09-22T14:08:38.960-07:00", Kind:"compute#operation", Name:"operation-1632344918636-5cc9beb06b628-fbcfe528-ebfc5d8d", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/operations/operation-1632344918636-5cc9beb06b628-fbcfe528-ebfc5d8d", StartTime:"2021-09-22T14:08:38.981-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x74e14533bfe625b9, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/firewalls/cml-zy17mvyf5l-egress", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:39 GMT"}, "Etag":[]string{"zb2aH4GXbDfyKl9Tvl7zLZtUa1w=/qnLN9gWIeHRiYYUwVbFfrfSkpRs="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:39.430Z
2021-09-22T21:08:39.430Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:39 [TRACE] Waiting 500ms before next try: timestamp=2021-09-22T21:08:39.430Z
2021-09-22T21:08:40.182Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:40 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x165f477ebfe625b9, InsertTime:"2021-09-22T14:08:38.960-07:00", Kind:"compute#operation", Name:"operation-1632344918636-5cc9beb06b628-fbcfe528-ebfc5d8d", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/operations/operation-1632344918636-5cc9beb06b628-fbcfe528-ebfc5d8d", StartTime:"2021-09-22T14:08:38.981-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x74e14533bfe625b9, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/firewalls/cml-zy17mvyf5l-egress", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:40 GMT"}, "Etag":[]string{"uLL1urbfCACdOPwYO3E0-WdXz8M=/uCEWfPqKxPhWeKfchAyFzfGA5j0="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:40.182Z
2021-09-22T21:08:40.182Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:40 [TRACE] Waiting 1s before next try: timestamp=2021-09-22T21:08:40.182Z
2021-09-22T21:08:41.481Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:41 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x165f477ebfe625b9, InsertTime:"2021-09-22T14:08:38.960-07:00", Kind:"compute#operation", Name:"operation-1632344918636-5cc9beb06b628-fbcfe528-ebfc5d8d", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/operations/operation-1632344918636-5cc9beb06b628-fbcfe528-ebfc5d8d", StartTime:"2021-09-22T14:08:38.981-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x74e14533bfe625b9, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/firewalls/cml-zy17mvyf5l-egress", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:41 GMT"}, "Etag":[]string{"wDQrshHSH9phfADPneKQr1-2xwc=/uI7C5AtuKNWjIXEYD0m0w9vNnDY="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:41.480Z
2021-09-22T21:08:41.481Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:41 [TRACE] Waiting 2s before next try: timestamp=2021-09-22T21:08:41.481Z
2021-09-22T21:08:43.061Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:08:43.061Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:43.062Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:43.734Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:43 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"2021-09-22T14:08:42.379-07:00", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x165f477ebfe625b9, InsertTime:"2021-09-22T14:08:38.960-07:00", Kind:"compute#operation", Name:"operation-1632344918636-5cc9beb06b628-fbcfe528-ebfc5d8d", OperationType:"insert", Progress:100, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/operations/operation-1632344918636-5cc9beb06b628-fbcfe528-ebfc5d8d", StartTime:"2021-09-22T14:08:38.981-07:00", Status:"DONE", StatusMessage:"", TargetId:0x74e14533bfe625b9, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/firewalls/cml-zy17mvyf5l-egress", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:43 GMT"}, "Etag":[]string{"USwLxdNi1dYZMgP2XVcgjSPSH4s=/bse7NlzNm_O0iy6ssRSJ46mVbvc="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:43.732Z
2021-09-22T21:08:44.475Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:44 [DEBUG] Waiting for state to become: [success]: timestamp=2021-09-22T21:08:44.475Z
2021-09-22T21:08:44.702Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:44 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x30c9deb7bfe625b3, InsertTime:"2021-09-22T14:08:44.154-07:00", Kind:"compute#operation", Name:"operation-1632344923775-5cc9beb552273-7b636619-9ea97ce4", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/operations/operation-1632344923775-5cc9beb552273-7b636619-9ea97ce4", StartTime:"2021-09-22T14:08:44.177-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x4a7726b6bfe625b3, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/firewalls/cml-zy17mvyf5l-ingress", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:44 GMT"}, "Etag":[]string{"WoUNJ5fUgbGsiBXJdvO6xaT3xnM=/SRigxm2i6qrlT_LCZjTuUQ32SuU="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:44.701Z
2021-09-22T21:08:44.702Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:44 [TRACE] Waiting 500ms before next try: timestamp=2021-09-22T21:08:44.701Z
2021-09-22T21:08:45.471Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:45 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x30c9deb7bfe625b3, InsertTime:"2021-09-22T14:08:44.154-07:00", Kind:"compute#operation", Name:"operation-1632344923775-5cc9beb552273-7b636619-9ea97ce4", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/operations/operation-1632344923775-5cc9beb552273-7b636619-9ea97ce4", StartTime:"2021-09-22T14:08:44.177-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x4a7726b6bfe625b3, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/firewalls/cml-zy17mvyf5l-ingress", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:45 GMT"}, "Etag":[]string{"rmrXT4gjfRcQfEWaSWn6qqC6AOo=/a75HOSv2HKwmgdaiZ0xy49hyDr4="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:45.470Z
2021-09-22T21:08:45.471Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:45 [TRACE] Waiting 1s before next try: timestamp=2021-09-22T21:08:45.471Z
2021-09-22T21:08:46.715Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:46 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x30c9deb7bfe625b3, InsertTime:"2021-09-22T14:08:44.154-07:00", Kind:"compute#operation", Name:"operation-1632344923775-5cc9beb552273-7b636619-9ea97ce4", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/operations/operation-1632344923775-5cc9beb552273-7b636619-9ea97ce4", StartTime:"2021-09-22T14:08:44.177-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x4a7726b6bfe625b3, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/firewalls/cml-zy17mvyf5l-ingress", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:46 GMT"}, "Etag":[]string{"qjkCtN-Z0PRXgaxJczsPFbh9vKc=/Rlv1T99lS3nHREyDY4dbjzMeyYE="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:46.699Z
2021-09-22T21:08:46.715Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:46 [TRACE] Waiting 2s before next try: timestamp=2021-09-22T21:08:46.699Z
2021-09-22T21:08:48.072Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:48.072Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:08:48.072Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:48.943Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:48 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"2021-09-22T14:08:47.954-07:00", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x30c9deb7bfe625b3, InsertTime:"2021-09-22T14:08:44.154-07:00", Kind:"compute#operation", Name:"operation-1632344923775-5cc9beb552273-7b636619-9ea97ce4", OperationType:"insert", Progress:100, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/operations/operation-1632344923775-5cc9beb552273-7b636619-9ea97ce4", StartTime:"2021-09-22T14:08:44.177-07:00", Status:"DONE", StatusMessage:"", TargetId:0x4a7726b6bfe625b3, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/global/firewalls/cml-zy17mvyf5l-ingress", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:48 GMT"}, "Etag":[]string{"hCVkndRImlxELwT-ACsPoE2FOEM=/xZRbXGiXyMEQPUkkcYTTMB4HBFM="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:48.943Z
2021-09-22T21:08:50.360Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:50 [DEBUG] Waiting for state to become: [success]: timestamp=2021-09-22T21:08:50.360Z
2021-09-22T21:08:50.635Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:50 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x39cb5f75bfe6258d, InsertTime:"2021-09-22T14:08:50.168-07:00", Kind:"compute#operation", Name:"operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/operations/operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", StartTime:"2021-09-22T14:08:50.168-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x38ab3943bfe6258e, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/instances/cml-zy17mvyf5l", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:50 GMT"}, "Etag":[]string{"3zV1MaMPVUgDCgFPagSzOfWY0b0=/IlAOPNx7-CydhP9fcsljs13_tag="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:50.635Z
2021-09-22T21:08:50.635Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:50 [TRACE] Waiting 500ms before next try: timestamp=2021-09-22T21:08:50.635Z
2021-09-22T21:08:51.367Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:51 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x39cb5f75bfe6258d, InsertTime:"2021-09-22T14:08:50.168-07:00", Kind:"compute#operation", Name:"operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/operations/operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", StartTime:"2021-09-22T14:08:50.168-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x38ab3943bfe6258e, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/instances/cml-zy17mvyf5l", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:51 GMT"}, "Etag":[]string{"Jcegw7PyOKZF-csE1mHcyMFgi74=/Tj4hnPA4hAqbS3Lj0f4j3yf_5OA="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:51.367Z
2021-09-22T21:08:51.367Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:51 [TRACE] Waiting 1s before next try: timestamp=2021-09-22T21:08:51.367Z
2021-09-22T21:08:52.616Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:52 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x39cb5f75bfe6258d, InsertTime:"2021-09-22T14:08:50.168-07:00", Kind:"compute#operation", Name:"operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/operations/operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", StartTime:"2021-09-22T14:08:50.168-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x38ab3943bfe6258e, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/instances/cml-zy17mvyf5l", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:52 GMT"}, "Etag":[]string{"3zV1MaMPVUgDCgFPagSzOfWY0b0=/IlAOPNx7-CydhP9fcsljs13_tag="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:52.616Z
2021-09-22T21:08:52.616Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:52 [TRACE] Waiting 2s before next try: timestamp=2021-09-22T21:08:52.616Z
2021-09-22T21:08:53.073Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:53.073Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:53.073Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:08:54.877Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:54 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x39cb5f75bfe6258d, InsertTime:"2021-09-22T14:08:50.168-07:00", Kind:"compute#operation", Name:"operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", OperationType:"insert", Progress:0, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/operations/operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", StartTime:"2021-09-22T14:08:50.168-07:00", Status:"RUNNING", StatusMessage:"", TargetId:0x38ab3943bfe6258e, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/instances/cml-zy17mvyf5l", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:54 GMT"}, "Etag":[]string{"gHnEM869yddpo7f1B5VvouJG_Mc=/U-xsfhrwPbe-Rg1DNRH9BNgNGUg="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:54.877Z
2021-09-22T21:08:54.878Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:54 [TRACE] Waiting 4s before next try: timestamp=2021-09-22T21:08:54.877Z
2021-09-22T21:08:58.074Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:08:58.074Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:58.074Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:08:59.150Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:59 [DEBUG] Waiting for operation: (&compute.Operation{ClientOperationId:"", CreationTimestamp:"", Description:"", EndTime:"2021-09-22T14:08:58.306-07:00", Error:(*compute.OperationError)(nil), HttpErrorMessage:"", HttpErrorStatusCode:0, Id:0x39cb5f75bfe6258d, InsertTime:"2021-09-22T14:08:50.168-07:00", Kind:"compute#operation", Name:"operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", OperationType:"insert", Progress:100, Region:"", SelfLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/operations/operation-1632344929054-5cc9beba5b0c8-53b3d23b-526e3b51", StartTime:"2021-09-22T14:08:50.168-07:00", Status:"DONE", StatusMessage:"", TargetId:0x38ab3943bfe6258e, TargetLink:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b/instances/cml-zy17mvyf5l", User:"cml-runner@analytics-sandbox-314222.iam.gserviceaccount.com", Warnings:[]*compute.OperationWarnings(nil), Zone:"https://www.googleapis.com/compute/v1/projects/analytics-sandbox-314222/zones/us-west1-b", ServerResponse:googleapi.ServerResponse{HTTPStatusCode:200, Header:http.Header{"Alt-Svc":[]string{"h3=\":443\"; ma=2592000,h3-29=\":443\"; ma=2592000,h3-T051=\":443\"; ma=2592000,h3-Q050=\":443\"; ma=2592000,h3-Q046=\":443\"; ma=2592000,h3-Q043=\":443\"; ma=2592000,quic=\":443\"; ma=2592000; v=\"46,43\""}, "Cache-Control":[]string{"private"}, "Content-Type":[]string{"application/json; charset=UTF-8"}, "Date":[]string{"Wed, 22 Sep 2021 21:08:59 GMT"}, "Etag":[]string{"Xc0QGLpVuyH97BtzYJGuW1OLyik=/nMG12oJps4foJQw9genaVBzJPzI="}, "Server":[]string{"ESF"}, "Vary":[]string{"Origin", "X-Origin", "Referer"}, "X-Content-Type-Options":[]string{"nosniff"}, "X-Frame-Options":[]string{"SAMEORIGIN"}, "X-Xss-Protection":[]string{"0"}}}, ForceSendFields:[]string(nil), NullFields:[]string(nil)}, <nil>): timestamp=2021-09-22T21:08:59.148Z
2021-09-22T21:08:59.411Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:59 [DEBUG] Instance address: "35.212.165.130": timestamp=2021-09-22T21:08:59.409Z
2021-09-22T21:08:59.412Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:08:59 [DEBUG] Waiting for state to become: [success]: timestamp=2021-09-22T21:08:59.411Z
2021-09-22T21:09:01.413Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:01 [DEBUG] Collected log events: "": timestamp=2021-09-22T21:09:01.413Z
2021-09-22T21:09:01.413Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:01 [DEBUG] Connection errors: &net.OpError{Op:"dial", Net:"tcp", Source:net.Addr(nil), Addr:(*net.TCPAddr)(0xc00048def0), Err:(*poll.DeadlineExceededError)(0x2b46880)}: timestamp=2021-09-22T21:09:01.413Z
2021-09-22T21:09:01.413Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:01 [TRACE] Waiting 500ms before next try: timestamp=2021-09-22T21:09:01.413Z
2021-09-22T21:09:03.075Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:03.075Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:03.075Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:03.954Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:03 [DEBUG] Collected log events: "": timestamp=2021-09-22T21:09:03.947Z
2021-09-22T21:09:03.954Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:03 [DEBUG] Connection errors: &net.OpError{Op:"dial", Net:"tcp", Source:net.Addr(nil), Addr:(*net.TCPAddr)(0xc00048d5c0), Err:(*poll.DeadlineExceededError)(0x2b46880)}: timestamp=2021-09-22T21:09:03.949Z
2021-09-22T21:09:03.954Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:03 [TRACE] Waiting 1s before next try: timestamp=2021-09-22T21:09:03.949Z
2021-09-22T21:09:06.977Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:06 [DEBUG] Collected log events: "": timestamp=2021-09-22T21:09:06.968Z
2021-09-22T21:09:06.979Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:06 [DEBUG] Connection errors: &net.OpError{Op:"dial", Net:"tcp", Source:net.Addr(nil), Addr:(*net.TCPAddr)(0xc00048d680), Err:(*net.timeoutError)(0x2b46880)}: timestamp=2021-09-22T21:09:06.977Z
2021-09-22T21:09:06.979Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:06 [TRACE] Waiting 2s before next try: timestamp=2021-09-22T21:09:06.979Z
2021-09-22T21:09:08.077Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:08.077Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:08.077Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:10.983Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:10 [DEBUG] Collected log events: "": timestamp=2021-09-22T21:09:10.982Z
2021-09-22T21:09:10.983Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:10 [DEBUG] Connection errors: &net.OpError{Op:"dial", Net:"tcp", Source:net.Addr(nil), Addr:(*net.TCPAddr)(0xc000358db0), Err:(*net.timeoutError)(0x2b46880)}: timestamp=2021-09-22T21:09:10.982Z
2021-09-22T21:09:10.983Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:10 [TRACE] Waiting 4s before next try: timestamp=2021-09-22T21:09:10.982Z
2021-09-22T21:09:13.077Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:13.077Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:13.077Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:15.092Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:15 [DEBUG] Collected log events: "": timestamp=2021-09-22T21:09:15.063Z
2021-09-22T21:09:15.092Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:15 [DEBUG] Connection errors: &net.OpError{Op:"dial", Net:"tcp", Source:net.Addr(nil), Addr:(*net.TCPAddr)(0xc000358ed0), Err:(*os.SyscallError)(0xc000050020)}: timestamp=2021-09-22T21:09:15.063Z
2021-09-22T21:09:15.092Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:15 [TRACE] Waiting 8s before next try: timestamp=2021-09-22T21:09:15.063Z
2021-09-22T21:09:18.114Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:18.114Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:18.140Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:23.116Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:23.117Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:23.142Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:24.738Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:24 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:09:24 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:09:24.734Z
2021-09-22T21:09:24.738Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:24 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:09:24.738Z
2021-09-22T21:09:24.738Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:24 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:09:24.738Z
2021-09-22T21:09:28.120Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:28.120Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:28.161Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:33.121Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:33.121Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:33.163Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:35.900Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:35 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:09:35 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:09:35.900Z
2021-09-22T21:09:35.901Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:35 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:09:35.900Z
2021-09-22T21:09:35.901Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:35 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:09:35.900Z
2021-09-22T21:09:38.123Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:38.124Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:38.163Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:43.125Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:43.125Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:43.164Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:47.163Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:47 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:09:47 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:09:47.162Z
2021-09-22T21:09:47.163Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:47 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:09:47.163Z
2021-09-22T21:09:47.163Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:47 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:09:47.163Z
2021-09-22T21:09:48.149Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:48.171Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:48.174Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:53.152Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:53.171Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:53.176Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:58.153Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:58.173Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:09:58.176Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:09:58.373Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:58 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:09:58 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:09:58.373Z
2021-09-22T21:09:58.374Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:58 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:09:58.373Z
2021-09-22T21:09:58.374Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:09:58 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:09:58.374Z
2021-09-22T21:10:03.154Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:03.174Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:03.177Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:08.157Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:08.174Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:08.177Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:09.643Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:09 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:10:08 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:10:09.642Z
2021-09-22T21:10:09.643Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:09 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:10:09.642Z
2021-09-22T21:10:09.643Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:09 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:10:09.643Z
2021-09-22T21:10:13.158Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:13.174Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:13.178Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:18.160Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:18.176Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:18.179Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:20.920Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:20 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:10:20 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:10:20.919Z
2021-09-22T21:10:20.920Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:20 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:10:20.919Z
2021-09-22T21:10:20.920Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:20 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:10:20.920Z
2021-09-22T21:10:23.161Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:23.178Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:23.180Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:28.162Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:28.178Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:28.180Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:32.210Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:32 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:10:31 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:10:32.209Z
2021-09-22T21:10:32.210Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:32 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:10:32.209Z
2021-09-22T21:10:32.210Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:32 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:10:32.209Z
2021-09-22T21:10:33.163Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:33.179Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:33.181Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:38.164Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:38.180Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:38.182Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:43.165Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:43.181Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:43.183Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:44.567Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:44 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:10:44 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:10:44.567Z
2021-09-22T21:10:44.568Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:44 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:10:44.567Z
2021-09-22T21:10:44.568Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:44 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:10:44.567Z
2021-09-22T21:10:48.169Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:48.184Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:48.184Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:53.170Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:53.185Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:10:53.185Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:55.821Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:55 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:10:55 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:10:55.821Z
2021-09-22T21:10:55.821Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:55 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:10:55.821Z
2021-09-22T21:10:55.821Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:10:55 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:10:55.821Z
2021-09-22T21:10:58.172Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:58.186Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:10:58.186Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:03.173Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:03.186Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:03.187Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:07.054Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:07 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:11:06 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:11:07.054Z
2021-09-22T21:11:07.054Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:07 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:11:07.054Z
2021-09-22T21:11:07.054Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:07 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:11:07.054Z
2021-09-22T21:11:08.174Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:08.187Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:08.188Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:13.175Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:13.188Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:13.189Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:18.060Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:18 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:11:17 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:11:18.060Z
2021-09-22T21:11:18.060Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:18 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:11:18.060Z
2021-09-22T21:11:18.060Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:18 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:11:18.060Z
2021-09-22T21:11:18.177Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:18.189Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:18.189Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:23.179Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:23.231Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:23.231Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:28.179Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:28.232Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:28.232Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:29.243Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:29 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:11:29 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:11:29.243Z
2021-09-22T21:11:29.244Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:29 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:11:29.243Z
2021-09-22T21:11:29.244Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:29 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:11:29.243Z
2021-09-22T21:11:33.180Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:33.234Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:33.234Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:38.181Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:38.236Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:38.237Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:40.460Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:40 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:11:39 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:11:40.460Z
2021-09-22T21:11:40.460Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:40 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:11:40.460Z
2021-09-22T21:11:40.460Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:40 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:11:40.460Z
2021-09-22T21:11:43.182Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:43.237Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:43.237Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:48.184Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:48.238Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:48.238Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:51.511Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:51 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:11:51 UTC. --\n-- No entries --\n": timestamp=2021-09-22T21:11:51.510Z
2021-09-22T21:11:51.511Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:51 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:11:51.510Z
2021-09-22T21:11:51.511Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:11:51 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:11:51.511Z
2021-09-22T21:11:53.190Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:53.238Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:53.238Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:58.196Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:11:58.238Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:11:58.238Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:12:02.550Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:12:02 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:12:02 UTC. --\nSep 22 21:11:54 cml-zy17mvyf5l systemd[1]: Started cml.service.\nSep 22 21:12:02 cml-zy17mvyf5l cml.sh[16972]: {\"level\":\"info\",\"message\":\"Preparing workdir /tmp/tmp.jNrxSU2BfB/.cml/cml-p8lhbsk4c8...\"}\nSep 22 21:12:02 cml-zy17mvyf5l cml.sh[16972]: {\"level\":\"info\",\"message\":\"Launching github runner\"}\n": timestamp=2021-09-22T21:12:02.549Z
2021-09-22T21:12:02.550Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:12:02 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:12:02.549Z
2021-09-22T21:12:02.550Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:12:02 [TRACE] Waiting 10s before next try: timestamp=2021-09-22T21:12:02.549Z
2021-09-22T21:12:03.198Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:12:03.240Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:12:03.240Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:12:08.202Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:12:08.241Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:12:08.242Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
info: {"awsSecurityGroup":null,"cloud":"gcp","driver":"github","id":"iterative-14szf8c3gux0m","idleTimeout":1800,"image":null,"instanceGpu":"nogpu","instanceHddSize":10,"instanceIp":"35.212.165.130","instanceLaunchTime":"2021-09-22 21:08:59.409451662 +0000 UTC m=+26.031608731","instanceType":"m","labels":"cml","name":"cml-zy17mvyf5l","region":"us-west","repo":"https://github.com/PACCAR-Analytics/py-demo-mlops","single":true,"spot":false,"spotPrice":-1,"timeouts":null}
2021-09-22T21:12:13.206Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:12:13.242Z [TRACE] dag/walk: vertex "meta.count-boundary (EachMode fixup)" is waiting for "iterative_cml_runner.runner"
2021-09-22T21:12:13.242Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/iterative/iterative\"] (close)"
2021-09-22T21:12:13.558Z [WARN]  Provider "provider[\"registry.terraform.io/iterative/iterative\"]" produced an unexpected new value for iterative_cml_runner.runner, 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:
      - .startup_script: inconsistent values for sensitive attribute
      - .ssh_private: inconsistent values for sensitive attribute
2021-09-22T21:12:13.559Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for iterative_cml_runner.runner
2021-09-22T21:12:13.559Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for iterative_cml_runner.runner
2021-09-22T21:12:13.559Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for iterative_cml_runner.runner
2021-09-22T21:12:13.559Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for iterative_cml_runner.runner
2021-09-22T21:12:13.560Z [TRACE] statemgr.Filesystem: creating backup snapshot at terraform.tfstate.backup
2021-09-22T21:12:13.560Z [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 8
2021-09-22T21:12:13.561Z [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2021-09-22T21:12:13.561Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:12:13 [DEBUG] Collected log events: "-- Logs begin at Wed 2021-09-22 21:09:09 UTC, end at Wed 2021-09-22 21:12:12 UTC. --\nSep 22 21:11:54 cml-zy17mvyf5l systemd[1]: Started cml.service.\nSep 22 21:12:02 cml-zy17mvyf5l cml.sh[16972]: {\"level\":\"info\",\"message\":\"Preparing workdir /tmp/tmp.jNrxSU2BfB/.cml/cml-p8lhbsk4c8...\"}\nSep 22 21:12:02 cml-zy17mvyf5l cml.sh[16972]: {\"level\":\"info\",\"message\":\"Launching github runner\"}\nSep 22 21:12:10 cml-zy17mvyf5l cml.sh[16972]: {\"level\":\"warn\",\"message\":\"SpotNotifier can not be started.\"}\nSep 22 21:12:10 cml-zy17mvyf5l cml.sh[16972]: {\"date\":\"2021-09-22T21:12:10.951Z\",\"level\":\"info\",\"message\":\"runner log Unrecognized command-line input arguments: 'once'. For usage refer to: .\\\\config.cmd --help or ./config.sh --help\",\"repo\":\"https://github.com/PACCAR-Analytics/py-demo-mlops\"}\nSep 22 21:12:11 cml-zy17mvyf5l cml.sh[16972]: {\"date\":\"2021-09-22T21:12:11.693Z\",\"level\":\"info\",\"message\":\"runner log √ Connected to GitHub\",\"repo\":\"https://github.com/PACCAR-Analytics/py-demo-mlops\"}\nSep 22 21:12:12 cml-zy17mvyf5l cml.sh[16972]: {\"date\":\"2021-09-22T21:12:12.148Z\",\"level\":\"info\",\"message\":\"runner log Listening for Jobs\",\"repo\":\"https://github.com/PACCAR-Analytics/py-demo-mlops\",\"status\":\"ready\"}\n": timestamp=2021-09-22T21:12:13.556Z
2021-09-22T21:12:13.562Z [INFO]  provider.terraform-provider-iterative_v0.7.3: 2021/09/22 21:12:13 [DEBUG] Connection errors: <nil>: timestamp=2021-09-22T21:12:13.556Z
2021-09-22T21:12:13.564Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:12:13.564Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2021-09-22T21:12:13.564Z [TRACE] GRPCProvider: Close
2021-09-22T21:12:13.564Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2021-09-22T21:12:13.564Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2021-09-22T21:12:13.565Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T21:12:13.569Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=663
2021-09-22T21:12:13.569Z [DEBUG] provider: plugin exited
2021-09-22T21:12:13.570Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": visit complete
2021-09-22T21:12:13.570Z [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2021-09-22T21:12:13.570Z [TRACE] vertex "root": visit complete
2021-09-22T21:12:13.570Z [TRACE] statemgr.Filesystem: have already backed up original terraform.tfstate to terraform.tfstate.backup on a previous write
2021-09-22T21:12:13.571Z [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 9
2021-09-22T21:12:13.571Z [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2021-09-22T21:12:13.573Z [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2021-09-22T21:12:13.574Z [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
^C
[1]+  Done                    TF_LOG=TRACE TF_LOG_PATH=/tmp/terraform.log cml-runner --single --log=debug --idle-timeout=1800 --token=[REDACTED] --cloud=gcp --cloud-region=us-west --cloud-type=m --cloud-gpu=nogpu --repo=[REDACTED] --driver=github --cloud-hdd-size=10
root@83cdbfd70274:/home/runner#

GCP activity:

dacbd · 2021-09-22T21:52:02Z

FAILED GITHUB ACTIONS RUN:

  deploy-runner:
    runs-on: ubuntu-latest
    steps:
      - uses: DanielBarnes/setup-cml@2970b7282efdade5a071a7be526162a4edcb149b
      - uses: actions/checkout@v2
      - name: Deploy runner on GCP # https://cml.dev/doc/self-hosted-runners#cloud-compute-resource-credentials
        env:
          GOOGLE_APPLICATION_CREDENTIALS_DATA: ${{ secrets.GCP_CML_RUNNER_KEY }}
        run: |
          touch /tmp/terraform.log
          TF_LOG=TRACE TF_LOG_PATH=/tmp/terraform.log cml-runner \
            --single \
            --log=debug \
            --idle-timeout=1800 \
            --token=${{ secrets.PAT_DCB }} \
            --cloud=gcp \
            --cloud-region=us-west \
            --cloud-type=m \
            --cloud-gpu=nogpu \
            --cloud-hdd-size=10 & tail -f /tmp/terraform.log

LOG:

##[debug]Evaluating: secrets.GCP_CML_RUNNER_KEY
##[debug]Evaluating Index:
##[debug]..Evaluating secrets:
##[debug]..=> Object
##[debug]..Evaluating String:
##[debug]..=> 'GCP_CML_RUNNER_KEY'
##[debug]=> '***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]***'
##[debug]Result: '***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]  ***
##[debug]***'
##[debug]Evaluating condition for step: 'Deploy runner on GCP'
##[debug]Evaluating: success()
##[debug]Evaluating success:
##[debug]=> true
##[debug]Result: true
##[debug]Starting: Deploy runner on GCP
##[debug]Loading inputs
##[debug]Evaluating: format('touch /tmp/terraform.log
##[debug]TF_LOG=TRACE TF_LOG_PATH=/tmp/terraform.log cml-runner \
##[debug]  --single \
##[debug]  --log=debug \
##[debug]  --idle-timeout=1800 \
##[debug]  --token=***0*** \
##[debug]  --cloud=gcp \
##[debug]  --cloud-region=us-west \
##[debug]  --cloud-type=m \
##[debug]  --cloud-gpu=nogpu \
##[debug]  --cloud-hdd-size=10 & tail -f /tmp/terraform.log
##[debug]', secrets.PAT_DCB)
##[debug]Evaluating format:
##[debug]..Evaluating String:
##[debug]..=> 'touch /tmp/terraform.log
##[debug]TF_LOG=TRACE TF_LOG_PATH=/tmp/terraform.log cml-runner \
##[debug]  --single \
##[debug]  --log=debug \
##[debug]  --idle-timeout=1800 \
##[debug]  --token=***0*** \
##[debug]  --cloud=gcp \
##[debug]  --cloud-region=us-west \
##[debug]  --cloud-type=m \
##[debug]  --cloud-gpu=nogpu \
##[debug]  --cloud-hdd-size=10 & tail -f /tmp/terraform.log
##[debug]'
##[debug]..Evaluating Index:
##[debug]....Evaluating secrets:
##[debug]....=> Object
##[debug]....Evaluating String:
##[debug]....=> 'PAT_DCB'
##[debug]..=> '***'
##[debug]=> 'touch /tmp/terraform.log
##[debug]TF_LOG=TRACE TF_LOG_PATH=/tmp/terraform.log cml-runner \
##[debug]  --single \
##[debug]  --log=debug \
##[debug]  --idle-timeout=1800 \
##[debug]  --token=*** \
##[debug]  --cloud=gcp \
##[debug]  --cloud-region=us-west \
##[debug]  --cloud-type=m \
##[debug]  --cloud-gpu=nogpu \
##[debug]  --cloud-hdd-size=10 & tail -f /tmp/terraform.log
##[debug]'
##[debug]Result: 'touch /tmp/terraform.log
##[debug]TF_LOG=TRACE TF_LOG_PATH=/tmp/terraform.log cml-runner \
##[debug]  --single \
##[debug]  --log=debug \
##[debug]  --idle-timeout=1800 \
##[debug]  --token=*** \
##[debug]  --cloud=gcp \
##[debug]  --cloud-region=us-west \
##[debug]  --cloud-type=m \
##[debug]  --cloud-gpu=nogpu \
##[debug]  --cloud-hdd-size=10 & tail -f /tmp/terraform.log
##[debug]'
##[debug]Loading env
Run touch /tmp/terraform.log
##[debug]/usr/bin/bash -e /home/runner/work/_temp/6e5931a9-1179-4c71-a61c-6c7f4ce8d803.sh
2021-09-22T21:35:47.822Z [DEBUG] Adding temp file log sink: /tmp/terraform-log445738605
2021-09-22T21:35:47.822Z [INFO]  Terraform version: 1.0.6
2021-09-22T21:35:47.822Z [INFO]  Go runtime version: go1.16.4
2021-09-22T21:35:47.822Z [INFO]  CLI args: []string***"/usr/local/bin/terraform", "version", "-json"***
2021-09-22T21:35:47.822Z [TRACE] Stdout is not a terminal
2021-09-22T21:35:47.822Z [TRACE] Stderr is not a terminal
2021-09-22T21:35:47.822Z [TRACE] Stdin is not a terminal
2021-09-22T21:35:47.822Z [DEBUG] Attempting to open CLI config file: /home/runner/.terraformrc
2021-09-22T21:35:47.822Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T21:35:47.823Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T21:35:47.824Z [DEBUG] ignoring non-existing provider search directory /home/runner/.terraform.d/plugins
2021-09-22T21:35:47.824Z [DEBUG] ignoring non-existing provider search directory /home/runner/.local/share/terraform/plugins
2021-09-22T21:35:47.824Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T21:35:47.825Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T21:35:47.859Z [INFO]  CLI command args: []string***"version", "-json"***
***"level":"info","message":"Preparing workdir /home/runner/.cml/cml-xv2hfhmygd..."***
***"level":"info","message":"Deploying cloud runner plan..."***
***"level":"info","message":"Terraform apply..."***
2021-09-22T21:35:48.753Z [DEBUG] Adding temp file log sink: /tmp/terraform-log341615476
2021-09-22T21:35:48.753Z [INFO]  Terraform version: 1.0.6
2021-09-22T21:35:48.755Z [INFO]  Go runtime version: go1.16.4
2021-09-22T21:35:48.755Z [INFO]  CLI args: []string***"/usr/local/bin/terraform", "-chdir=/home/runner/.cml/cml-xv2hfhmygd", "init"***
2021-09-22T21:35:48.755Z [TRACE] Stdout is not a terminal
2021-09-22T21:35:48.755Z [TRACE] Stderr is not a terminal
2021-09-22T21:35:48.755Z [TRACE] Stdin is not a terminal
2021-09-22T21:35:48.755Z [DEBUG] Attempting to open CLI config file: /home/runner/.terraformrc
2021-09-22T21:35:48.755Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T21:35:48.755Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T21:35:48.755Z [DEBUG] ignoring non-existing provider search directory /home/runner/.terraform.d/plugins
2021-09-22T21:35:48.755Z [DEBUG] ignoring non-existing provider search directory /home/runner/.local/share/terraform/plugins
2021-09-22T21:35:48.755Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T21:35:48.755Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T21:35:48.758Z [INFO]  CLI command args: []string***"init"***
2021-09-22T21:35:48.928Z [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2021-09-22T21:35:48.928Z [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2021-09-22T21:35:48.935Z [DEBUG] New state was assigned lineage "305ec041-e8a3-a32c-cd37-266f6e48919f"
2021-09-22T21:35:48.935Z [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2021-09-22T21:35:48.935Z [TRACE] Meta.Backend: instantiated backend of type <nil>
2021-09-22T21:35:48.936Z [DEBUG] checking for provisioner in "."
2021-09-22T21:35:48.936Z [DEBUG] checking for provisioner in "/usr/local/bin"
2021-09-22T21:35:48.936Z [INFO]  Failed to read plugin lock file .terraform/plugins/linux_amd64/lock.json: open .terraform/plugins/linux_amd64/lock.json: no such file or directory
2021-09-22T21:35:48.937Z [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2021-09-22T21:35:48.937Z [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
2021-09-22T21:35:48.937Z [TRACE] statemgr.Filesystem: reading initial snapshot from terraform.tfstate
2021-09-22T21:35:48.937Z [TRACE] statemgr.Filesystem: snapshot file has nil snapshot, but that's okay
2021-09-22T21:35:48.937Z [TRACE] statemgr.Filesystem: read nil snapshot
2021-09-22T21:35:48.939Z [DEBUG] Service discovery for registry.terraform.io at https://registry.terraform.io/.well-known/terraform.json
2021-09-22T21:35:48.939Z [TRACE] HTTP client GET request to https://registry.terraform.io/.well-known/terraform.json
2021-09-22T21:35:48.996Z [DEBUG] GET https://registry.terraform.io/v1/providers/iterative/iterative/versions
2021-09-22T21:35:48.996Z [TRACE] HTTP client GET request to https://registry.terraform.io/v1/providers/iterative/iterative/versions
2021-09-22T21:35:49.086Z [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2021-09-22T21:35:49.087Z [TRACE] getproviders.SearchLocalDirectory: failed to resolve symlinks for .terraform/providers: lstat .terraform: no such file or directory
2021-09-22T21:35:49.087Z [TRACE] providercache.fillMetaCache: error while scanning directory .terraform/providers: cannot search .terraform/providers: lstat .terraform/providers: no such file or directory
2021-09-22T21:35:49.087Z [DEBUG] GET https://registry.terraform.io/v1/providers/iterative/iterative/0.7.3/download/linux/amd64
2021-09-22T21:35:49.087Z [TRACE] HTTP client GET request to https://registry.terraform.io/v1/providers/iterative/iterative/0.7.3/download/linux/amd64
2021-09-22T21:35:49.114Z [DEBUG] GET https://github.com/iterative/terraform-provider-iterative/releases/download/v0.7.3/terraform-provider-iterative_0.7.3_SHA256SUMS
2021-09-22T21:35:49.114Z [TRACE] HTTP client GET request to https://github.com/iterative/terraform-provider-iterative/releases/download/v0.7.3/terraform-provider-iterative_0.7.3_SHA256SUMS
2021-09-22T21:35:49.298Z [TRACE] HTTP client GET request to https://releases.521000.best/292388086/dfe4d008-8aff-473a-81e4-fb1985d22236?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAIWNJYAX4CSVEH53A%2F20210922%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20210922T213549Z&X-Amz-Expires=300&X-Amz-Signature=a1bf1179c307aec58a1a09acea1676ab9f92b2f004fd1575cd426d1bed06942d&X-Amz-SignedHeaders=host&actor_id=0&key_id=0&repo_id=292388086&response-content-disposition=attachment%3B%20filename%3Dterraform-provider-iterative_0.7.3_SHA256SUMS&response-content-type=application%2Foctet-stream
2021-09-22T21:35:49.396Z [DEBUG] GET https://github.com/iterative/terraform-provider-iterative/releases/download/v0.7.3/terraform-provider-iterative_0.7.3_SHA256SUMS.sig
2021-09-22T21:35:49.396Z [TRACE] HTTP client GET request to https://github.com/iterative/terraform-provider-iterative/releases/download/v0.7.3/terraform-provider-iterative_0.7.3_SHA256SUMS.sig
2021-09-22T21:35:49.550Z [TRACE] HTTP client GET request to https://releases.521000.best/292388086/e4d68c54-251a-4d3b-a346-ab0b697ac998?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAIWNJYAX4CSVEH53A%2F20210922%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20210922T213549Z&X-Amz-Expires=300&X-Amz-Signature=62f775a0246d6925aff48946294a847b18b82bed61a2c1f67738a6c1daa104ce&X-Amz-SignedHeaders=host&actor_id=0&key_id=0&repo_id=292388086&response-content-disposition=attachment%3B%20filename%3Dterraform-provider-iterative_0.7.3_SHA256SUMS.sig&response-content-type=application%2Foctet-stream
2021-09-22T21:35:49.637Z [TRACE] providercache.Dir.InstallPackage: installing registry.terraform.io/iterative/iterative v0.7.3 from https://github.com/iterative/terraform-provider-iterative/releases/download/v0.7.3/terraform-provider-iterative_0.7.3_linux_amd64.zip
2021-09-22T21:35:49.637Z [TRACE] HTTP client GET request to https://github.com/iterative/terraform-provider-iterative/releases/download/v0.7.3/terraform-provider-iterative_0.7.3_linux_amd64.zip
2021-09-22T21:35:49.832Z [TRACE] HTTP client GET request to https://releases.521000.best/292388086/22ffa97f-357c-44f9-9089-0cd0284ba7ad?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAIWNJYAX4CSVEH53A%2F20210922%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20210922T213549Z&X-Amz-Expires=300&X-Amz-Signature=5c9c859e8bb9c42d8fb6d8593ca75fe6b97cf814f2c831dc38c9bd90d27ca60e&X-Amz-SignedHeaders=host&actor_id=0&key_id=0&repo_id=292388086&response-content-disposition=attachment%3B%20filename%3Dterraform-provider-iterative_0.7.3_linux_amd64.zip&response-content-type=application%2Foctet-stream
2021-09-22T21:35:50.291Z [DEBUG] Provider signed by 910A9EF2910B1E37 Iterative (Terraform provider key) <eng@iterative.ai>
2021-09-22T21:35:50.775Z [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2021-09-22T21:35:50.775Z [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/iterative/iterative v0.7.3 for linux_amd64 at .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64
2021-09-22T21:35:50.775Z [TRACE] providercache.fillMetaCache: including .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64 as a candidate package for registry.terraform.io/iterative/iterative 0.7.3
2021-09-22T21:35:51.021Z [DEBUG] Adding temp file log sink: /tmp/terraform-log867697031
2021-09-22T21:35:51.022Z [INFO]  Terraform version: 1.0.6
2021-09-22T21:35:51.022Z [INFO]  Go runtime version: go1.16.4
2021-09-22T21:35:51.023Z [INFO]  CLI args: []string***"/usr/local/bin/terraform", "-chdir=/home/runner/.cml/cml-xv2hfhmygd", "apply", "-auto-approve"***
2021-09-22T21:35:51.023Z [TRACE] Stdout is not a terminal
2021-09-22T21:35:51.023Z [TRACE] Stderr is not a terminal
2021-09-22T21:35:51.024Z [TRACE] Stdin is not a terminal
2021-09-22T21:35:51.024Z [DEBUG] Attempting to open CLI config file: /home/runner/.terraformrc
2021-09-22T21:35:51.024Z [DEBUG] File doesn't exist, but doesn't need to. Ignoring.
2021-09-22T21:35:51.025Z [DEBUG] ignoring non-existing provider search directory terraform.d/plugins
2021-09-22T21:35:51.025Z [DEBUG] ignoring non-existing provider search directory /home/runner/.terraform.d/plugins
2021-09-22T21:35:51.025Z [DEBUG] ignoring non-existing provider search directory /home/runner/.local/share/terraform/plugins
2021-09-22T21:35:51.025Z [DEBUG] ignoring non-existing provider search directory /usr/local/share/terraform/plugins
2021-09-22T21:35:51.025Z [DEBUG] ignoring non-existing provider search directory /usr/share/terraform/plugins
2021-09-22T21:35:51.025Z [INFO]  CLI command args: []string***"apply", "-auto-approve"***
2021-09-22T21:35:51.027Z [TRACE] Meta.Backend: no config given or present on disk, so returning nil config
2021-09-22T21:35:51.027Z [TRACE] Meta.Backend: backend has not previously been initialized in this working directory
2021-09-22T21:35:51.027Z [DEBUG] New state was assigned lineage "ccc22c7a-41db-cc6e-6e51-b22a80c14dbf"
2021-09-22T21:35:51.027Z [TRACE] Meta.Backend: using default local state only (no backend configuration, and no existing initialized backend)
2021-09-22T21:35:51.027Z [TRACE] Meta.Backend: instantiated backend of type <nil>
2021-09-22T21:35:51.027Z [TRACE] providercache.fillMetaCache: scanning directory .terraform/providers
2021-09-22T21:35:51.028Z [TRACE] getproviders.SearchLocalDirectory: found registry.terraform.io/iterative/iterative v0.7.3 for linux_amd64 at .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64
2021-09-22T21:35:51.028Z [TRACE] providercache.fillMetaCache: including .terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64 as a candidate package for registry.terraform.io/iterative/iterative 0.7.3
2021-09-22T21:35:51.200Z [DEBUG] checking for provisioner in "."
2021-09-22T21:35:51.201Z [DEBUG] checking for provisioner in "/usr/local/bin"
2021-09-22T21:35:51.201Z [INFO]  Failed to read plugin lock file .terraform/plugins/linux_amd64/lock.json: open .terraform/plugins/linux_amd64/lock.json: no such file or directory
2021-09-22T21:35:51.201Z [TRACE] Meta.Backend: backend <nil> does not support operations, so wrapping it in a local backend
2021-09-22T21:35:51.201Z [INFO]  backend/local: starting Apply operation
2021-09-22T21:35:51.202Z [TRACE] backend/local: requesting state manager for workspace "default"
2021-09-22T21:35:51.202Z [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
2021-09-22T21:35:51.202Z [TRACE] backend/local: requesting state lock for workspace "default"
2021-09-22T21:35:51.202Z [TRACE] statemgr.Filesystem: preparing to manage state snapshots at terraform.tfstate
2021-09-22T21:35:51.202Z [TRACE] statemgr.Filesystem: no previously-stored snapshot exists
2021-09-22T21:35:51.202Z [TRACE] statemgr.Filesystem: locking terraform.tfstate using fcntl flock
2021-09-22T21:35:51.202Z [TRACE] statemgr.Filesystem: writing lock metadata to .terraform.tfstate.lock.info
2021-09-22T21:35:51.202Z [TRACE] backend/local: reading remote state for workspace "default"
2021-09-22T21:35:51.202Z [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2021-09-22T21:35:51.202Z [TRACE] statemgr.Filesystem: snapshot file has nil snapshot, but that's okay
2021-09-22T21:35:51.202Z [TRACE] statemgr.Filesystem: read nil snapshot
2021-09-22T21:35:51.202Z [TRACE] backend/local: retrieving local state snapshot for workspace "default"
2021-09-22T21:35:51.202Z [TRACE] backend/local: building context for current working directory
2021-09-22T21:35:51.202Z [TRACE] terraform.NewContext: starting
2021-09-22T21:35:51.231Z [TRACE] terraform.NewContext: loading provider schemas
2021-09-22T21:35:51.300Z [TRACE] LoadSchemas: retrieving schema for provider type "registry.terraform.io/iterative/iterative"
2021-09-22T21:35:51.301Z [DEBUG] created provider logger: level=trace
2021-09-22T21:35:51.301Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T21:35:51.358Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T21:35:51.358Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2470
2021-09-22T21:35:51.364Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T21:35:51.385Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T21:35:51.385Z
2021-09-22T21:35:51.446Z [DEBUG] provider: using plugin: version=5
2021-09-22T21:35:51.446Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin480542569 network=unix timestamp=2021-09-22T21:35:51.445Z
2021-09-22T21:35:51.561Z [TRACE] GRPCProvider: GetProviderSchema
2021-09-22T21:35:51.561Z [TRACE] provider.stdio: waiting for stdio data
2021-09-22T21:35:51.575Z [TRACE] GRPCProvider: Close
2021-09-22T21:35:51.576Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T21:35:51.578Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2470
2021-09-22T21:35:51.578Z [DEBUG] provider: plugin exited
2021-09-22T21:35:51.578Z [TRACE] terraform.NewContext: complete
2021-09-22T21:35:51.578Z [TRACE] backend/local: finished building terraform.Context
2021-09-22T21:35:51.578Z [TRACE] backend/local: requesting interactive input, if necessary
2021-09-22T21:35:51.578Z [TRACE] Context.Input: Prompting for provider arguments
2021-09-22T21:35:51.578Z [TRACE] Context.Input: Provider provider.iterative declared at main.tf:11,1-21
2021-09-22T21:35:51.578Z [TRACE] Context.Input: Input for provider.iterative: map[string]cty.Value***
2021-09-22T21:35:51.578Z [TRACE] backend/local: running validation operation
2021-09-22T21:35:51.587Z [INFO]  terraform: building graph: GraphTypeValidate
2021-09-22T21:35:51.588Z [TRACE] Executing graph transform *terraform.ConfigTransformer
2021-09-22T21:35:51.588Z [TRACE] ConfigTransformer: Starting for path:
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
  ------
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.LocalTransformer
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.OutputTransformer
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.StateTransformer
2021-09-22T21:35:51.594Z [TRACE] StateTransformer: state is empty, so nothing to do
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021-09-22T21:35:51.594Z [TRACE] AttachResourceConfigTransformer: attaching to "iterative_cml_runner.runner" (*terraform.NodeValidatableResource) config from main.tf:14,1-41
2021-09-22T21:35:51.594Z [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to iterative_cml_runner.runner
2021-09-22T21:35:51.594Z [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021-09-22T21:35:51.594Z [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021-09-22T21:35:51.594Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021-09-22T21:35:51.601Z [TRACE] ProviderConfigTransformer: attaching to "provider[\"registry.terraform.io/iterative/iterative\"]" provider configuration from main.tf:11,1-21
2021-09-22T21:35:51.602Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.602Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021-09-22T21:35:51.602Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021-09-22T21:35:51.602Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021-09-22T21:35:51.602Z [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/iterative/iterative"] serving iterative_cml_runner.runner
2021-09-22T21:35:51.602Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:51.602Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.602Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021-09-22T21:35:51.602Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021-09-22T21:35:51.602Z [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.602Z [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021-09-22T21:35:51.602Z [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021-09-22T21:35:51.602Z [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021-09-22T21:35:51.602Z [TRACE] AttachSchemaTransformer: attaching resource schema to iterative_cml_runner.runner
2021-09-22T21:35:51.602Z [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:51.602Z [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021-09-22T21:35:51.602Z [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021-09-22T21:35:51.602Z [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021-09-22T21:35:51.602Z [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021-09-22T21:35:51.602Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T21:35:51.602Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T21:35:51.602Z [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021-09-22T21:35:51.603Z [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021-09-22T21:35:51.603Z [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021-09-22T21:35:51.603Z [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2021-09-22T21:35:51.603Z [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2021-09-22T21:35:51.603Z [TRACE] Executing graph transform *terraform.TargetsTransformer
2021-09-22T21:35:51.603Z [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021-09-22T21:35:51.603Z [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021-09-22T21:35:51.603Z [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021-09-22T21:35:51.603Z [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021-09-22T21:35:51.603Z [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.603Z [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021-09-22T21:35:51.603Z [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.603Z [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021-09-22T21:35:51.603Z [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:35:51.603Z [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021-09-22T21:35:51.603Z [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeValidatableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeValidatableResource
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:35:51.603Z [DEBUG] Starting graph walk: walkValidate
2021-09-22T21:35:51.603Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": starting visit (*terraform.NodeApplyableProvider)
2021-09-22T21:35:51.604Z [DEBUG] created provider logger: level=trace
2021-09-22T21:35:51.604Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T21:35:51.665Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T21:35:51.666Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2477
2021-09-22T21:35:51.667Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T21:35:51.690Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T21:35:51.689Z
2021-09-22T21:35:51.742Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin755807092 network=unix timestamp=2021-09-22T21:35:51.742Z
2021-09-22T21:35:51.742Z [DEBUG] provider: using plugin: version=5
2021-09-22T21:35:51.816Z [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/iterative/iterative\"]" provider for provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:51.816Z [TRACE] buildProviderConfig for provider["registry.terraform.io/iterative/iterative"]: using explicit config only
2021-09-22T21:35:51.816Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": visit complete
2021-09-22T21:35:51.817Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodeValidatableResource)
2021-09-22T21:35:51.817Z [TRACE] GRPCProvider: ValidateResourceConfig
2021-09-22T21:35:51.817Z [TRACE] GRPCProvider: GetProviderSchema
2021-09-22T21:35:51.817Z [TRACE] provider.stdio: waiting for stdio data
2021-09-22T21:35:51.821Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:35:51.821Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2021-09-22T21:35:51.821Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2021-09-22T21:35:51.821Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2021-09-22T21:35:51.821Z [TRACE] GRPCProvider: Close
2021-09-22T21:35:51.822Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T21:35:51.824Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2477
2021-09-22T21:35:51.824Z [DEBUG] provider: plugin exited
2021-09-22T21:35:51.824Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": visit complete
2021-09-22T21:35:51.824Z [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2021-09-22T21:35:51.825Z [TRACE] vertex "root": visit complete
2021-09-22T21:35:51.825Z [INFO]  backend/local: apply calling Plan
2021-09-22T21:35:51.825Z [INFO]  terraform: building graph: GraphTypePlan
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.ConfigTransformer
2021-09-22T21:35:51.825Z [TRACE] ConfigTransformer: Starting for path:
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
  ------
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.LocalTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.OutputTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.OrphanResourceInstanceTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.OrphanResourceInstanceTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.StateTransformer
2021-09-22T21:35:51.825Z [TRACE] StateTransformer: state is empty, so nothing to do
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.StateTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021-09-22T21:35:51.825Z [TRACE] AttachResourceConfigTransformer: attaching to "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandPlannableResource) config from main.tf:14,1-41
2021-09-22T21:35:51.825Z [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to iterative_cml_runner.runner (expand)
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021-09-22T21:35:51.825Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021-09-22T21:35:51.825Z [TRACE] ProviderConfigTransformer: attaching to "provider[\"registry.terraform.io/iterative/iterative\"]" provider configuration from main.tf:11,1-21
2021-09-22T21:35:51.825Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.825Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021-09-22T21:35:51.825Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021-09-22T21:35:51.825Z [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/iterative/iterative"] serving iterative_cml_runner.runner (expand)
2021-09-22T21:35:51.825Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:51.825Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.825Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021-09-22T21:35:51.825Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021-09-22T21:35:51.825Z [TRACE] AttachSchemaTransformer: attaching resource schema to iterative_cml_runner.runner (expand)
2021-09-22T21:35:51.825Z [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021-09-22T21:35:51.825Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner (expand)" references: []
2021-09-22T21:35:51.825Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021-09-22T21:35:51.825Z [TRACE] AttachDependenciesTransformer: iterative_cml_runner.runner depends on []
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.attachDataResourceDependsOnTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.attachDataResourceDependsOnTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.TargetsTransformer
2021-09-22T21:35:51.825Z [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021-09-22T21:35:51.825Z [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021-09-22T21:35:51.825Z [TRACE] ForcedCBDTransformer: "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandPlannableResource) has no CBD descendent, so skipping
2021-09-22T21:35:51.826Z [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021-09-22T21:35:51.826Z [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021-09-22T21:35:51.826Z [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.826Z [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021-09-22T21:35:51.826Z [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:51.826Z [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021-09-22T21:35:51.826Z [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:35:51.826Z [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021-09-22T21:35:51.826Z [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandPlannableResource
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:35:51.826Z [DEBUG] Starting graph walk: walkPlan
2021-09-22T21:35:51.826Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": starting visit (*terraform.NodeApplyableProvider)
2021-09-22T21:35:51.826Z [DEBUG] created provider logger: level=trace
2021-09-22T21:35:51.826Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T21:35:51.897Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T21:35:51.898Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2484
2021-09-22T21:35:51.898Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T21:35:51.923Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T21:35:51.923Z
2021-09-22T21:35:51.985Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin574024968 network=unix timestamp=2021-09-22T21:35:51.985Z
2021-09-22T21:35:51.985Z [DEBUG] provider: using plugin: version=5
2021-09-22T21:35:52.050Z [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/iterative/iterative\"]" provider for provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:52.050Z [TRACE] buildProviderConfig for provider["registry.terraform.io/iterative/iterative"]: using explicit config only
2021-09-22T21:35:52.050Z [TRACE] GRPCProvider: GetProviderSchema
2021-09-22T21:35:52.050Z [TRACE] provider.stdio: waiting for stdio data
2021-09-22T21:35:52.056Z [TRACE] GRPCProvider: ValidateProviderConfig
2021-09-22T21:35:52.057Z [TRACE] GRPCProvider: ConfigureProvider
2021-09-22T21:35:52.058Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": visit complete
2021-09-22T21:35:52.058Z [TRACE] vertex "iterative_cml_runner.runner (expand)": starting visit (*terraform.nodeExpandPlannableResource)
2021-09-22T21:35:52.058Z [TRACE] vertex "iterative_cml_runner.runner (expand)": expanding dynamic subgraph
2021-09-22T21:35:52.058Z [TRACE] vertex "iterative_cml_runner.runner (expand)": entering dynamic subgraph
2021-09-22T21:35:52.058Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodePlannableResource)
2021-09-22T21:35:52.058Z [TRACE] vertex "iterative_cml_runner.runner": expanding dynamic subgraph
2021-09-22T21:35:52.058Z [TRACE] Executing graph transform *terraform.ResourceCountTransformer
2021-09-22T21:35:52.058Z [TRACE] ResourceCountTransformer: adding iterative_cml_runner.runner as *terraform.NodePlannableResourceInstance
2021-09-22T21:35:52.058Z [TRACE] Completed graph transform *terraform.ResourceCountTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodePlannableResourceInstance
  ------
2021-09-22T21:35:52.058Z [TRACE] Executing graph transform *terraform.OrphanResourceInstanceCountTransformer
2021-09-22T21:35:52.058Z [TRACE] Completed graph transform *terraform.OrphanResourceInstanceCountTransformer (no changes)
2021-09-22T21:35:52.058Z [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021-09-22T21:35:52.058Z [DEBUG] Resource instance state not found for node "iterative_cml_runner.runner", instance iterative_cml_runner.runner
2021-09-22T21:35:52.058Z [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021-09-22T21:35:52.058Z [TRACE] Executing graph transform *terraform.TargetsTransformer
2021-09-22T21:35:52.058Z [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021-09-22T21:35:52.058Z [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021-09-22T21:35:52.058Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T21:35:52.058Z [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021-09-22T21:35:52.058Z [TRACE] Executing graph transform *terraform.RootTransformer
2021-09-22T21:35:52.058Z [TRACE] Completed graph transform *terraform.RootTransformer (no changes)
2021-09-22T21:35:52.058Z [TRACE] vertex "iterative_cml_runner.runner": entering dynamic subgraph
2021-09-22T21:35:52.058Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodePlannableResourceInstance)
2021-09-22T21:35:52.058Z [TRACE] readResourceInstanceState: reading state for iterative_cml_runner.runner
2021-09-22T21:35:52.058Z [TRACE] readResourceInstanceState: no state present for iterative_cml_runner.runner
2021-09-22T21:35:52.058Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to prevRunState for iterative_cml_runner.runner
2021-09-22T21:35:52.058Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for iterative_cml_runner.runner
2021-09-22T21:35:52.058Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for iterative_cml_runner.runner
2021-09-22T21:35:52.058Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for iterative_cml_runner.runner
2021-09-22T21:35:52.077Z [TRACE] NodeAbstractResourceInstance.refresh for iterative_cml_runner.runner
2021-09-22T21:35:52.077Z [DEBUG] refresh: iterative_cml_runner.runner: no state, so not refreshing
2021-09-22T21:35:52.077Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to refreshState for iterative_cml_runner.runner
2021-09-22T21:35:52.077Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: removing state object for iterative_cml_runner.runner
2021-09-22T21:35:52.078Z [TRACE] Re-validating config for "iterative_cml_runner.runner"
2021-09-22T21:35:52.078Z [TRACE] GRPCProvider: ValidateResourceConfig
2021-09-22T21:35:52.079Z [TRACE] GRPCProvider: PlanResourceChange
2021-09-22T21:35:52.081Z [WARN]  Provider "registry.terraform.io/iterative/iterative" produced an invalid plan for iterative_cml_runner.runner, 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:
      - .spot: planned value cty.False for a non-computed attribute
2021-09-22T21:35:52.081Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for iterative_cml_runner.runner
2021-09-22T21:35:52.081Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for iterative_cml_runner.runner
2021-09-22T21:35:52.081Z [TRACE] writeChange: recorded Create change for iterative_cml_runner.runner
2021-09-22T21:35:52.081Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:35:52.081Z [TRACE] vertex "iterative_cml_runner.runner": dynamic subgraph completed successfully
2021-09-22T21:35:52.081Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:35:52.081Z [TRACE] vertex "iterative_cml_runner.runner (expand)": dynamic subgraph completed successfully
2021-09-22T21:35:52.081Z [TRACE] vertex "iterative_cml_runner.runner (expand)": visit complete
2021-09-22T21:35:52.081Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": starting visit (*terraform.NodeCountBoundary)
2021-09-22T21:35:52.081Z [TRACE] vertex "meta.count-boundary (EachMode fixup)": visit complete
2021-09-22T21:35:52.081Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": starting visit (*terraform.graphNodeCloseProvider)
2021-09-22T21:35:52.081Z [TRACE] GRPCProvider: Close
2021-09-22T21:35:52.082Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T21:35:52.083Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2484
2021-09-22T21:35:52.083Z [DEBUG] provider: plugin exited
2021-09-22T21:35:52.083Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"] (close)": visit complete
2021-09-22T21:35:52.083Z [TRACE] vertex "root": starting visit (*terraform.nodeCloseModule)
2021-09-22T21:35:52.083Z [TRACE] vertex "root": visit complete
2021-09-22T21:35:52.085Z [INFO]  backend/local: apply calling Apply
2021-09-22T21:35:52.085Z [INFO]  terraform: building graph: GraphTypeApply
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.ConfigTransformer
2021-09-22T21:35:52.085Z [TRACE] ConfigTransformer: Starting for path:
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.ConfigTransformer with new graph:
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  ------
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.RootVariableTransformer
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.RootVariableTransformer (no changes)
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.ModuleVariableTransformer
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.ModuleVariableTransformer (no changes)
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.LocalTransformer
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.LocalTransformer (no changes)
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.OutputTransformer
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.OutputTransformer (no changes)
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.DiffTransformer
2021-09-22T21:35:52.085Z [TRACE] DiffTransformer starting
2021-09-22T21:35:52.085Z [TRACE] DiffTransformer: found Create change for iterative_cml_runner.runner
2021-09-22T21:35:52.085Z [TRACE] DiffTransformer: iterative_cml_runner.runner will be represented by iterative_cml_runner.runner
2021-09-22T21:35:52.085Z [TRACE] DiffTransformer complete
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.DiffTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  ------
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.AttachStateTransformer
2021-09-22T21:35:52.085Z [DEBUG] Resource state not found for node "iterative_cml_runner.runner", instance iterative_cml_runner.runner
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.AttachStateTransformer (no changes)
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.OrphanOutputTransformer
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.OrphanOutputTransformer (no changes)
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.AttachResourceConfigTransformer
2021-09-22T21:35:52.085Z [TRACE] AttachResourceConfigTransformer: attaching to "iterative_cml_runner.runner" (*terraform.NodeApplyableResourceInstance) config from main.tf:14,1-41
2021-09-22T21:35:52.085Z [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to iterative_cml_runner.runner
2021-09-22T21:35:52.085Z [TRACE] AttachResourceConfigTransformer: attaching to "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandApplyableResource) config from main.tf:14,1-41
2021-09-22T21:35:52.085Z [TRACE] AttachResourceConfigTransformer: attaching provider meta configs to iterative_cml_runner.runner (expand)
2021-09-22T21:35:52.085Z [TRACE] Completed graph transform *terraform.AttachResourceConfigTransformer (no changes)
2021-09-22T21:35:52.085Z [TRACE] Executing graph transform *terraform.graphTransformerMulti
2021-09-22T21:35:52.085Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderConfigTransformer
2021-09-22T21:35:52.085Z [TRACE] ProviderConfigTransformer: attaching to "provider[\"registry.terraform.io/iterative/iterative\"]" provider configuration from main.tf:11,1-21
2021-09-22T21:35:52.085Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderConfigTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:52.085Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.MissingProviderTransformer
2021-09-22T21:35:52.085Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.MissingProviderTransformer (no changes)
2021-09-22T21:35:52.085Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.ProviderTransformer
2021-09-22T21:35:52.085Z [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/iterative/iterative"] serving iterative_cml_runner.runner
2021-09-22T21:35:52.086Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner" (*terraform.NodeApplyableResourceInstance) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:52.086Z [TRACE] ProviderTransformer: exact match for provider["registry.terraform.io/iterative/iterative"] serving iterative_cml_runner.runner (expand)
2021-09-22T21:35:52.086Z [DEBUG] ProviderTransformer: "iterative_cml_runner.runner (expand)" (*terraform.nodeExpandApplyableResource) needs provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:52.086Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.ProviderTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:52.086Z [TRACE] (graphTransformerMulti) Executing graph transform *terraform.PruneProviderTransformer
2021-09-22T21:35:52.086Z [TRACE] (graphTransformerMulti) Completed graph transform *terraform.PruneProviderTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.graphTransformerMulti with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.RemovedModuleTransformer
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.RemovedModuleTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.AttachSchemaTransformer
2021-09-22T21:35:52.086Z [TRACE] AttachSchemaTransformer: attaching resource schema to iterative_cml_runner.runner (expand)
2021-09-22T21:35:52.086Z [TRACE] AttachSchemaTransformer: attaching resource schema to iterative_cml_runner.runner
2021-09-22T21:35:52.086Z [TRACE] AttachSchemaTransformer: attaching provider config schema to provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.AttachSchemaTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.ModuleExpansionTransformer
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.ModuleExpansionTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.ReferenceTransformer
2021-09-22T21:35:52.086Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner (expand)" references: []
2021-09-22T21:35:52.086Z [DEBUG] ReferenceTransformer: "iterative_cml_runner.runner" references: []
2021-09-22T21:35:52.086Z [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/iterative/iterative\"]" references: []
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.ReferenceTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.AttachDependenciesTransformer
2021-09-22T21:35:52.086Z [TRACE] AttachDependenciesTransformer: iterative_cml_runner.runner depends on []
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.AttachDependenciesTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.ForcedCBDTransformer
2021-09-22T21:35:52.086Z [TRACE] ForcedCBDTransformer: "iterative_cml_runner.runner" (*terraform.NodeApplyableResourceInstance) has no CBD descendent, so skipping
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.ForcedCBDTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.DestroyEdgeTransformer
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.DestroyEdgeTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.CBDEdgeTransformer
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.CBDEdgeTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.pruneUnusedNodesTransformer
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.pruneUnusedNodesTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.TargetsTransformer
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.TargetsTransformer (no changes)
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.CountBoundaryTransformer
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.CountBoundaryTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.CloseProviderTransformer
2021-09-22T21:35:52.086Z [TRACE] Completed graph transform *terraform.CloseProviderTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  ------
2021-09-22T21:35:52.086Z [TRACE] Executing graph transform *terraform.CloseRootModuleTransformer
2021-09-22T21:35:52.087Z [TRACE] Completed graph transform *terraform.CloseRootModuleTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:35:52.087Z [TRACE] Executing graph transform *terraform.TransitiveReductionTransformer
2021-09-22T21:35:52.087Z [TRACE] Completed graph transform *terraform.TransitiveReductionTransformer with new graph:
  iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
    iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
  iterative_cml_runner.runner (expand) - *terraform.nodeExpandApplyableResource
    provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
  provider["registry.terraform.io/iterative/iterative"] - *terraform.NodeApplyableProvider
  provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
    iterative_cml_runner.runner - *terraform.NodeApplyableResourceInstance
  root - *terraform.nodeCloseModule
    meta.count-boundary (EachMode fixup) - *terraform.NodeCountBoundary
    provider["registry.terraform.io/iterative/iterative"] (close) - *terraform.graphNodeCloseProvider
  ------
2021-09-22T21:35:52.087Z [DEBUG] Starting graph walk: walkApply
2021-09-22T21:35:52.087Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": starting visit (*terraform.NodeApplyableProvider)
2021-09-22T21:35:52.087Z [DEBUG] created provider logger: level=trace
2021-09-22T21:35:52.087Z [INFO]  provider: configuring client automatic mTLS
2021-09-22T21:35:52.145Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 args=[.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3]
2021-09-22T21:35:52.146Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2491
2021-09-22T21:35:52.146Z [DEBUG] provider: waiting for RPC address: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3
2021-09-22T21:35:52.171Z [INFO]  provider.terraform-provider-iterative_v0.7.3: configuring server automatic mTLS: timestamp=2021-09-22T21:35:52.170Z
2021-09-22T21:35:52.233Z [DEBUG] provider: using plugin: version=5
2021-09-22T21:35:52.233Z [DEBUG] provider.terraform-provider-iterative_v0.7.3: plugin address: address=/tmp/plugin929307216 network=unix timestamp=2021-09-22T21:35:52.233Z
2021-09-22T21:35:52.303Z [TRACE] BuiltinEvalContext: Initialized "provider[\"registry.terraform.io/iterative/iterative\"]" provider for provider["registry.terraform.io/iterative/iterative"]
2021-09-22T21:35:52.304Z [TRACE] buildProviderConfig for provider["registry.terraform.io/iterative/iterative"]: using explicit config only
2021-09-22T21:35:52.303Z [TRACE] provider.stdio: waiting for stdio data
2021-09-22T21:35:52.304Z [TRACE] GRPCProvider: GetProviderSchema
2021-09-22T21:35:52.306Z [TRACE] GRPCProvider: ValidateProviderConfig
2021-09-22T21:35:52.307Z [TRACE] GRPCProvider: ConfigureProvider
2021-09-22T21:35:52.308Z [TRACE] vertex "provider[\"registry.terraform.io/iterative/iterative\"]": visit complete
2021-09-22T21:35:52.308Z [TRACE] vertex "iterative_cml_runner.runner (expand)": starting visit (*terraform.nodeExpandApplyableResource)
2021-09-22T21:35:52.308Z [TRACE] vertex "iterative_cml_runner.runner (expand)": expanding dynamic subgraph
2021-09-22T21:35:52.308Z [TRACE] vertex "iterative_cml_runner.runner (expand)": entering dynamic subgraph
2021-09-22T21:35:52.308Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodeApplyableResource)
2021-09-22T21:35:52.308Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:35:52.308Z [TRACE] vertex "iterative_cml_runner.runner (expand)": dynamic subgraph completed successfully
2021-09-22T21:35:52.308Z [TRACE] vertex "iterative_cml_runner.runner (expand)": visit complete
2021-09-22T21:35:52.308Z [TRACE] vertex "iterative_cml_runner.runner": starting visit (*terraform.NodeApplyableResourceInstance)
2021-09-22T21:35:52.308Z [TRACE] readDiff: Read Create change from plan for iterative_cml_runner.runner
2021-09-22T21:35:52.308Z [TRACE] readResourceInstanceState: reading state for iterative_cml_runner.runner
2021-09-22T21:35:52.308Z [TRACE] readResourceInstanceState: no state present for iterative_cml_runner.runner
2021-09-22T21:35:52.308Z [TRACE] readDiff: Read Create change from plan for iterative_cml_runner.runner
2021-09-22T21:35:52.308Z [TRACE] Re-validating config for "iterative_cml_runner.runner"
2021-09-22T21:35:52.308Z [TRACE] GRPCProvider: ValidateResourceConfig
2021-09-22T21:35:52.310Z [TRACE] GRPCProvider: PlanResourceChange
2021-09-22T21:35:52.312Z [WARN]  Provider "registry.terraform.io/iterative/iterative" produced an invalid plan for iterative_cml_runner.runner, 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:
      - .spot: planned value cty.False for a non-computed attribute
2021-09-22T21:35:52.312Z [TRACE] checkPlannedChange: Verifying that actual change (action Create) matches planned change (action Create)
2021-09-22T21:35:52.312Z [TRACE] readResourceInstanceState: reading state for iterative_cml_runner.runner
2021-09-22T21:35:52.312Z [TRACE] readResourceInstanceState: no state present for iterative_cml_runner.runner
2021-09-22T21:35:52.312Z [INFO]  Starting apply for iterative_cml_runner.runner
2021-09-22T21:35:52.313Z [DEBUG] iterative_cml_runner.runner: applying the planned Create change
2021-09-22T21:35:52.313Z [TRACE] GRPCProvider: ApplyResourceChange
2021-09-22T21:35:54.518Z [TRACE] maybeTainted: iterative_cml_runner.runner encountered an error during creation, so it is now marked as tainted
2021-09-22T21:35:54.518Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for iterative_cml_runner.runner
2021-09-22T21:35:54.518Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for iterative_cml_runner.runner
2021-09-22T21:35:54.518Z [TRACE] evalApplyProvisioners: iterative_cml_runner.runner is tainted, so skipping provisioning
2021-09-22T21:35:54.519Z [TRACE] maybeTainted: iterative_cml_runner.runner was already tainted, so nothing to do
2021-09-22T21:35:54.519Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState to workingState for iterative_cml_runner.runner
2021-09-22T21:35:54.519Z [TRACE] NodeAbstractResouceInstance.writeResourceInstanceState: writing state object for iterative_cml_runner.runner
2021-09-22T21:35:54.519Z [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2021-09-22T21:35:54.519Z [TRACE] statemgr.Filesystem: snapshot file has nil snapshot, but that's okay
2021-09-22T21:35:54.519Z [TRACE] statemgr.Filesystem: read nil snapshot
2021-09-22T21:35:54.519Z [TRACE] statemgr.Filesystem: no original state snapshot to back up
2021-09-22T21:35:54.519Z [TRACE] statemgr.Filesystem: state has changed since last snapshot, so incrementing serial to 1
2021-09-22T21:35:54.519Z [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2021-09-22T21:35:54.529Z [TRACE] vertex "iterative_cml_runner.runner": visit complete
2021-09-22T21:35:54.529Z [TRACE] dag/walk: upstream of "meta.count-boundary (EachMode fixup)" errored, so skipping
2021-09-22T21:35:54.529Z [TRACE] dag/walk: upstream of "provider[\"registry.terraform.io/iterative/iterative\"] (close)" errored, so skipping
2021-09-22T21:35:54.529Z [TRACE] dag/walk: upstream of "root" errored, so skipping
2021-09-22T21:35:54.529Z [TRACE] statemgr.Filesystem: reading latest snapshot from terraform.tfstate
2021-09-22T21:35:54.530Z [TRACE] statemgr.Filesystem: read snapshot with lineage "397eeb8c-729d-3a7a-949b-e7589b64075a" serial 1
2021-09-22T21:35:54.530Z [TRACE] statemgr.Filesystem: no original state snapshot to back up
2021-09-22T21:35:54.530Z [TRACE] statemgr.Filesystem: no state changes since last snapshot
2021-09-22T21:35:54.530Z [TRACE] statemgr.Filesystem: writing snapshot at terraform.tfstate
2021-09-22T21:35:54.534Z [TRACE] statemgr.Filesystem: removing lock metadata file .terraform.tfstate.lock.info
2021-09-22T21:35:54.534Z [TRACE] statemgr.Filesystem: unlocking terraform.tfstate using fcntl flock
2021-09-22T21:35:54.535Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = transport is closing"
2021-09-22T21:35:54.537Z [DEBUG] provider: plugin process exited: path=.terraform/providers/registry.terraform.io/iterative/iterative/0.7.3/linux_amd64/terraform-provider-iterative_v0.7.3 pid=2491
2021-09-22T21:35:54.537Z [DEBUG] provider: plugin exited
***"level":"error"***
***"level":"error","status":"terminated"***
##[debug]Re-evaluate condition on job cancellation for step: 'Deploy runner on GCP'.
Error: The operation was canceled.
##[debug]System.OperationCanceledException: The operation was canceled.
##[debug]   at System.Threading.CancellationToken.ThrowOperationCanceledException()
##[debug]   at GitHub.Runner.Sdk.ProcessInvoker.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, Channel`1 redirectStandardIn, Boolean inheritConsoleHandler, Boolean keepStandardInOpen, Boolean highPriorityProcess, CancellationToken cancellationToken)
##[debug]   at GitHub.Runner.Common.ProcessInvokerWrapper.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, Channel`1 redirectStandardIn, Boolean inheritConsoleHandler, Boolean keepStandardInOpen, Boolean highPriorityProcess, CancellationToken cancellationToken)
##[debug]   at GitHub.Runner.Worker.Handlers.DefaultStepHost.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, Boolean inheritConsoleHandler, CancellationToken cancellationToken)
##[debug]   at GitHub.Runner.Worker.Handlers.ScriptHandler.RunAsync(ActionRunStage stage)
##[debug]   at GitHub.Runner.Worker.ActionRunner.RunAsync()
##[debug]   at GitHub.Runner.Worker.StepsRunner.RunStepAsync(IStep step, CancellationToken jobCancellationToken)
##[debug]Finishing: Deploy runner on GCP

GCP activity:

dacbd/action.yml

Select an option

No results found

Select an option

No results found

dacbd commented Sep 22, 2021

Uh oh!

0x2b3bfa0 commented Sep 22, 2021 •

edited

Loading

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

	deploy-runner:
	runs-on: ubuntu-latest
	steps:
	- uses: DanielBarnes/setup-cml@2970b7282efdade5a071a7be526162a4edcb149b
	- uses: actions/checkout@v2
	- name: Deploy runner on GCP # https://cml.dev/doc/self-hosted-runners#cloud-compute-resource-credentials
	env:
	GOOGLE_APPLICATION_CREDENTIALS_DATA: ${{ secrets.GCP_CML_RUNNER_KEY }}
	run: \|
	cml-runner \
	--log=debug \
	--single \
	--idle-timeout=1800 \
	--token=${{ secrets.PAT_DCB }} \
	--cloud=gcp \
	--cloud-region=us-west \
	--cloud-type=m \
	--cloud-gpu=nogpu \
	--cloud-hdd-size=10

dacbd/action.yml

dacbd commented Sep 22, 2021

Uh oh!

0x2b3bfa0 commented Sep 22, 2021 • edited Loading Uh oh! There was an error while loading. Please reload this page.

Uh oh!

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

dacbd commented Sep 22, 2021

Uh oh!

0x2b3bfa0 commented Sep 22, 2021 •

edited

Loading