Skip to content

Instantly share code, notes, and snippets.

@Tensho
Created January 31, 2019 14:46
Show Gist options
  • Save Tensho/190a3b8e2ed32e45614252a349c7c899 to your computer and use it in GitHub Desktop.
Save Tensho/190a3b8e2ed32e45614252a349c7c899 to your computer and use it in GitHub Desktop.
Terraform remote-exec provisioner glitch
module.instance.aws_instance.precious: Provisioning with 'remote-exec'...
module.instance.aws_instance.precious (remote-exec): Connecting to remote host via SSH...
module.instance.aws_instance.precious (remote-exec): Host: 100.100.100.100
module.instance.aws_instance.precious (remote-exec): User: ubuntu
module.instance.aws_instance.precious (remote-exec): Password: false
2019-01-31T16:06:51.444+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:51 [DEBUG] connecting to TCP connection for SSH
2019-01-31T16:06:51.444+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:51 [DEBUG] Connecting to bastion: 100.100.100.100:22
module.instance.aws_instance.precious (remote-exec): Private key: false
module.instance.aws_instance.precious (remote-exec): SSH Agent: true
module.instance.aws_instance.precious (remote-exec): Checking Host Key: false
module.instance.aws_instance.precious (remote-exec): Using configured bastion host...
module.instance.aws_instance.precious (remote-exec): Host: 200.200.200.200
module.instance.aws_instance.precious (remote-exec): User: bastion-user
module.instance.aws_instance.precious (remote-exec): Password: false
module.instance.aws_instance.precious (remote-exec): Private key: false
module.instance.aws_instance.precious (remote-exec): SSH Agent: true
module.instance.aws_instance.precious (remote-exec): Checking Host Key: false
2019-01-31T16:06:52.348+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:52 [DEBUG] Connecting via bastion (200.200.200.200:22) to host: 100.100.100.100:22
2019-01-31T16:06:52.404+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:52 [DEBUG] handshaking with SSH
2019-01-31T16:06:53.032+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] Telling SSH config to forward to agent
2019-01-31T16:06:53.032+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] Setting up a session to request agent forwarding
2019-01-31T16:06:53.032+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] opening new ssh session
2019/01/31 16:06:53 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:06:53 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:06:53 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:06:53 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:06:53 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:06:53 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
2019/01/31 16:06:53 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019-01-31T16:06:53.377+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [INFO] agent forwarding enabled
module.instance.aws_instance.precious (remote-exec): Connected!
2019-01-31T16:06:53.377+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] opening new ssh session
2019-01-31T16:06:53.433+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] Starting remote scp process: scp -vt /tmp
2019-01-31T16:06:53.489+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] Started SCP session, beginning transfers...
2019-01-31T16:06:53.489+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] Beginning file upload...
2019-01-31T16:06:53.600+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] SCP session complete, closing stdin pipe.
2019-01-31T16:06:53.600+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] Waiting for SSH session to complete.
2019-01-31T16:06:53.710+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [ERROR] scp stderr: "Sink: C0644 47 terraform_1965839211.sh\n"
2019-01-31T16:06:53.710+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] opening new ssh session
2019-01-31T16:06:53.823+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] starting remote command: chmod 0777 /tmp/terraform_1965839211.sh
2019-01-31T16:06:53.937+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] remote command exited with '0': chmod 0777 /tmp/terraform_1965839211.sh
2019-01-31T16:06:53.937+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:53 [DEBUG] opening new ssh session
2019-01-31T16:06:54.049+0200 [DEBUG] plugin.terraform: remote-exec-provisioner (internal) 2019/01/31 16:06:54 [DEBUG] starting remote command: /tmp/terraform_1965839211.sh
module.instance.aws_instance.precious (remote-exec): /home/ubuntu
module.instance.aws_instance.precious (remote-exec): ubuntu
module.instance.aws_instance.precious (remote-exec): deploy@ip-100-100-100-100:/home/ubuntu$
2019/01/31 16:06:58 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:06:58 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:06:58 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:06:58 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:06:58 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:06:58 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:06:58 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
module.instance.aws_instance.precious: Still creating... (50s elapsed)
2019/01/31 16:07:03 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:07:03 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:03 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:03 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:03 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:03 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
2019/01/31 16:07:03 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:08 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:07:08 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:08 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:08 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:08 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:08 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:08 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
module.instance.aws_instance.precious: Still creating... (1m0s elapsed)
2019/01/31 16:07:13 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:13 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:13 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:13 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:13 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:13 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:07:13 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
2019/01/31 16:07:18 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:07:18 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
2019/01/31 16:07:18 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:18 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:18 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:18 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:18 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
module.instance.aws_instance.precious: Still creating... (1m10s elapsed)
2019/01/31 16:07:23 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:07:23 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
2019/01/31 16:07:23 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:23 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:23 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:23 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:23 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:28 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:28 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:28 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:28 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:07:28 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:28 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
2019/01/31 16:07:28 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
module.instance.aws_instance.precious: Still creating... (1m20s elapsed)
2019/01/31 16:07:33 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:07:33 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:33 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:33 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:33 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
2019/01/31 16:07:33 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:33 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:38 [TRACE] dag/walk: vertex "root", waiting for: "meta.count-boundary (count boundary fixup)"
2019/01/31 16:07:38 [TRACE] dag/walk: vertex "provisioner.remote-exec (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:38 [TRACE] dag/walk: vertex "module.instance.aws_instance.precious (destroy)", waiting for: "module.instance.aws_instance.precious"
2019/01/31 16:07:38 [TRACE] dag/walk: vertex "provider.aws (close)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:38 [TRACE] dag/walk: vertex "output.private_ip", waiting for: "module.instance.output.private_ip"
2019/01/31 16:07:38 [TRACE] dag/walk: vertex "meta.count-boundary (count boundary fixup)", waiting for: "module.instance.aws_instance.precious (destroy)"
2019/01/31 16:07:38 [TRACE] dag/walk: vertex "module.instance.output.private_ip", waiting for: "module.instance.aws_instance.precious"
module.instance.aws_instance.precious: Still creating... (1m30s elapsed)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment