non-root.log

2019/08/02 16:54:39 [INFO] Packer version: 1.4.2
2019/08/02 16:54:39 Packer Target OS/Arch: linux amd64
2019/08/02 16:54:39 Built with Go Version: go1.12.6
2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 Using internal plugin for amazon-ebs
2019/08/02 16:54:39 Using internal plugin for openstack
2019/08/02 16:54:39 Using internal plugin for oracle-oci
2019/08/02 16:54:39 Using internal plugin for parallels-iso
2019/08/02 16:54:39 Using internal plugin for virtualbox-iso
2019/08/02 16:54:39 Using internal plugin for yandex
2019/08/02 16:54:39 Using internal plugin for googlecompute
2019/08/02 16:54:39 Using internal plugin for scaleway
2019/08/02 16:54:39 Using internal plugin for digitalocean
2019/08/02 16:54:39 Using internal plugin for hcloud
2019/08/02 16:54:39 Using internal plugin for hyperone
2019/08/02 16:54:39 Using internal plugin for parallels-pvm
2019/08/02 16:54:39 Using internal plugin for virtualbox-ovf
2019/08/02 16:54:39 Using internal plugin for vmware-iso
2019/08/02 16:54:39 Using internal plugin for amazon-chroot
2019/08/02 16:54:39 Using internal plugin for linode
2019/08/02 16:54:39 Using internal plugin for ncloud
2019/08/02 16:54:39 Using internal plugin for oracle-classic
2019/08/02 16:54:39 Using internal plugin for file
2019/08/02 16:54:39 Using internal plugin for hyperv-vmcx
2019/08/02 16:54:39 Using internal plugin for tencentcloud-cvm
2019/08/02 16:54:39 Using internal plugin for vmware-vmx
2019/08/02 16:54:39 Using internal plugin for triton
2019/08/02 16:54:39 Using internal plugin for alicloud-ecs
2019/08/02 16:54:39 Using internal plugin for amazon-ebsvolume
2019/08/02 16:54:39 Using internal plugin for cloudstack
2019/08/02 16:54:39 Using internal plugin for docker
2019/08/02 16:54:39 Using internal plugin for hyperv-iso
2019/08/02 16:54:39 Using internal plugin for lxc
2019/08/02 16:54:39 Using internal plugin for profitbricks
2019/08/02 16:54:39 Using internal plugin for amazon-ebssurrogate
2019/08/02 16:54:39 Using internal plugin for azure-arm
2019/08/02 16:54:39 Using internal plugin for lxd
2019/08/02 16:54:39 Using internal plugin for proxmox
2019/08/02 16:54:39 Using internal plugin for qemu
2019/08/02 16:54:39 Using internal plugin for amazon-instance
2019/08/02 16:54:39 Using internal plugin for null
2019/08/02 16:54:39 Using internal plugin for oneandone
2019/08/02 16:54:39 Using internal plugin for vagrant
2019/08/02 16:54:39 Using internal plugin for shell
2019/08/02 16:54:39 Using internal plugin for sleep
2019/08/02 16:54:39 Using internal plugin for windows-shell
2019/08/02 16:54:39 Using internal plugin for chef-solo
2019/08/02 16:54:39 Using internal plugin for puppet-masterless
2019/08/02 16:54:39 Using internal plugin for puppet-server
2019/08/02 16:54:39 Using internal plugin for salt-masterless
2019/08/02 16:54:39 Using internal plugin for ansible-local
2019/08/02 16:54:39 Using internal plugin for chef-client
2019/08/02 16:54:39 Using internal plugin for inspec
2019/08/02 16:54:39 Using internal plugin for windows-restart
2019/08/02 16:54:39 Using internal plugin for converge
2019/08/02 16:54:39 Using internal plugin for file
2019/08/02 16:54:39 Using internal plugin for powershell
2019/08/02 16:54:39 Using internal plugin for shell-local
2019/08/02 16:54:39 Using internal plugin for ansible
2019/08/02 16:54:39 Using internal plugin for breakpoint
2019/08/02 16:54:39 Using internal plugin for alicloud-import
2019/08/02 16:54:39 Using internal plugin for shell-local
2019/08/02 16:54:39 Using internal plugin for vagrant
2019/08/02 16:54:39 Using internal plugin for vagrant-cloud
2019/08/02 16:54:39 Using internal plugin for vsphere
2019/08/02 16:54:39 Using internal plugin for compress
2019/08/02 16:54:39 Using internal plugin for googlecompute-export
2019/08/02 16:54:39 Using internal plugin for googlecompute-import
2019/08/02 16:54:39 Using internal plugin for manifest
2019/08/02 16:54:39 Using internal plugin for vsphere-template
2019/08/02 16:54:39 Using internal plugin for amazon-import
2019/08/02 16:54:39 Using internal plugin for docker-push
2019/08/02 16:54:39 Using internal plugin for docker-tag
2019/08/02 16:54:39 Using internal plugin for artifice
2019/08/02 16:54:39 Using internal plugin for checksum
2019/08/02 16:54:39 Using internal plugin for digitalocean-import
2019/08/02 16:54:39 Using internal plugin for docker-import
2019/08/02 16:54:39 Using internal plugin for docker-save
2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 Attempting to open config file: /home/not-a-root-user/.packerconfig
2019/08/02 16:54:39 [WARN] Config file doesn't exist: /home/not-a-root-user/.packerconfig
2019/08/02 16:54:39 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[alicloud-ecs:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-alicloud-ecs amazon-chroot:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-chroot amazon-ebs:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebs amazon-ebssurrogate:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebssurrogate amazon-ebsvolume:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebsvolume amazon-instance:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-instance azure-arm:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-arm cloudstack:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-cloudstack digitalocean:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-digitalocean docker:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-docker file:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-file googlecompute:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-googlecompute hcloud:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hcloud hyperone:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperone hyperv-iso:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-iso hyperv-vmcx:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-vmcx linode:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-linode lxc:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxc lxd:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxd ncloud:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-ncloud null:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-null oneandone:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oneandone openstack:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-openstack oracle-classic:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-classic oracle-oci:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-oci parallels-iso:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-iso parallels-pvm:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-pvm profitbricks:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-profitbricks proxmox:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-proxmox qemu:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-qemu scaleway:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-scaleway tencentcloud-cvm:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-tencentcloud-cvm triton:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-triton vagrant:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vagrant virtualbox-iso:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-iso virtualbox-ovf:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-ovf vmware-iso:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-iso vmware-vmx:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-vmx yandex:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-yandex] PostProcessors:map[alicloud-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-alicloud-import amazon-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-amazon-import artifice:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-artifice checksum:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-checksum compress:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-compress digitalocean-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-digitalocean-import docker-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-import docker-push:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-push docker-save:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-save docker-tag:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-tag googlecompute-export:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-export googlecompute-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-import manifest:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-manifest shell-local:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-shell-local vagrant:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant vagrant-cloud:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant-cloud vsphere:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere vsphere-template:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere-template] Provisioners:map[ansible:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible ansible-local:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible-local breakpoint:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-breakpoint chef-client:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-client chef-solo:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-solo converge:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-converge file:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-file inspec:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-inspec powershell:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-powershell puppet-masterless:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-masterless puppet-server:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-server salt-masterless:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-salt-masterless shell:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell shell-local:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell-local sleep:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-sleep windows-restart:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-restart windows-shell:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-shell]}
2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 Setting cache directory: /demo/packer_cache
2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 Loading builder: amazon-ebs
2019/08/02 16:54:39 Plugin could not be found. Checking same directory as executable.
2019/08/02 16:54:39 Current exe path: /packer
2019/08/02 16:54:39 Creating plugin client for path: /packer
2019/08/02 16:54:39 Starting plugin: /packer []string{"/packer", "plugin", "packer-builder-amazon-ebs"}
2019/08/02 16:54:39 Waiting for RPC address for: /packer
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 [INFO] Packer version: 1.4.2
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Packer Target OS/Arch: linux amd64
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Built with Go Version: go1.12.6
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Attempting to open config file: /home/not-a-root-user/.packerconfig
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 [WARN] Config file doesn't exist: /home/not-a-root-user/.packerconfig
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Setting cache directory: /demo/packer_cache
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 args: []string{"packer-builder-amazon-ebs"}
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Plugin minimum port: 10000
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Plugin maximum port: 25000
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Plugin address: unix /tmp/packer-plugin638108658
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Waiting for connection...
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Serving a plugin connection...
2019/08/02 16:54:39 Loading provisioner: ansible
2019/08/02 16:54:39 Plugin could not be found. Checking same directory as executable.
2019/08/02 16:54:39 Current exe path: /packer
2019/08/02 16:54:39 Creating plugin client for path: /packer
2019/08/02 16:54:39 Starting plugin: /packer []string{"/packer", "plugin", "packer-provisioner-ansible"}
2019/08/02 16:54:39 Waiting for RPC address for: /packer
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 [INFO] Packer version: 1.4.2
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Packer Target OS/Arch: linux amd64
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Built with Go Version: go1.12.6
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Attempting to open config file: /home/not-a-root-user/.packerconfig
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 [WARN] Config file doesn't exist: /home/not-a-root-user/.packerconfig
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Setting cache directory: /demo/packer_cache
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 args: []string{"packer-provisioner-ansible"}
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Plugin minimum port: 10000
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Plugin maximum port: 25000
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Detected home directory from env var: /home/not-a-root-user
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Plugin address: unix /tmp/packer-plugin952720285
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Waiting for connection...
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Serving a plugin connection...
2019/08/02 16:54:39 Build debug mode: false
2019/08/02 16:54:39 Force build: false
2019/08/02 16:54:39 On error:
2019/08/02 16:54:39 Preparing build: amazon-ebs
amazon-ebs output will be in this color.

2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 ansible-playbook version: 2.8.3
2019/08/02 16:54:39 Waiting on builds to complete...
2019/08/02 16:54:39 Starting build run: amazon-ebs
2019/08/02 16:54:39 Running builder: amazon-ebs
2019/08/02 16:54:39 [INFO] (telemetry) Starting builder amazon-ebs
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 Found region us-east-2
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 [INFO] AWS Auth provider used: "SharedConfigCredentials: /home/not-a-root-user/.aws/credentials"
2019/08/02 16:54:39 packer: 2019/08/02 16:54:39 [INFO] (aws): No AWS timeout and polling overrides have been set. Packer will default to waiter-specific delays and timeouts. If you would like to customize the length of time between retries and max number of retries you may do so by setting the environment variables AWS_POLL_DELAY_SECONDS and AWS_MAX_ATTEMPTS to your desired values.
2019-08-02T16:54:39Z: ==> amazon-ebs: Prevalidating AMI Name: ansible-root-bug 2019-08-02T16-54-39Z
2019/08/02 16:54:40 packer: 2019/08/02 16:54:40 Using AMI Filters {
2019/08/02 16:54:40 packer:   ImageIds: ["ami-0f2b4fc905b0bd1f1"]
2019/08/02 16:54:40 packer: }
2019-08-02T16:54:40Z:     amazon-ebs: Found Image ID: ami-0f2b4fc905b0bd1f1
2019-08-02T16:54:40Z: ==> amazon-ebs: Creating temporary keypair: packer_5d446acf-efa0-53f3-4621-31c9231e779f
2019-08-02T16:54:40Z: ==> amazon-ebs: Creating temporary security group for this instance: packer_5d446ad0-9bfd-c7e3-30b8-1471a3daac84
2019/08/02 16:54:41 packer: 2019/08/02 16:54:41 [DEBUG] Waiting for temporary security group: sg-0b7e0efd288dd4f8d
2019/08/02 16:54:41 packer: 2019/08/02 16:54:41 [DEBUG] Found security group sg-0b7e0efd288dd4f8d
2019-08-02T16:54:41Z: ==> amazon-ebs: Authorizing access to port 22 from [0.0.0.0/0] in the temporary security groups...
2019-08-02T16:54:41Z: ==> amazon-ebs: Launching a source AWS instance...
2019-08-02T16:54:41Z: ==> amazon-ebs: Adding tags to source instance
2019-08-02T16:54:41Z:     amazon-ebs: Adding tag: "Name": "Packer Builder"
2019-08-02T16:54:42Z:     amazon-ebs: Instance ID: i-0dc5fd2f8a0d7c4d5
2019-08-02T16:54:42Z: ==> amazon-ebs: Waiting for instance (i-0dc5fd2f8a0d7c4d5) to become ready...
2019/08/02 16:54:58 packer: 2019/08/02 16:54:58 [INFO] Not using winrm communicator, skipping get password...
2019/08/02 16:54:58 packer: 2019/08/02 16:54:58 [INFO] Waiting for SSH, up to timeout: 5m0s
2019-08-02T16:54:58Z: ==> amazon-ebs: Using ssh communicator to connect: 18.216.81.163
2019-08-02T16:54:58Z: ==> amazon-ebs: Waiting for SSH to become available...
2019/08/02 16:55:09 packer: 2019/08/02 16:55:09 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:14 packer: 2019/08/02 16:55:14 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:19 packer: 2019/08/02 16:55:19 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:24 packer: 2019/08/02 16:55:24 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:29 packer: 2019/08/02 16:55:29 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:34 packer: 2019/08/02 16:55:34 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:39 packer: 2019/08/02 16:55:39 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:44 packer: 2019/08/02 16:55:44 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:49 packer: 2019/08/02 16:55:49 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:55:54 packer: 2019/08/02 16:55:54 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:56:00 packer: 2019/08/02 16:56:00 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 18.216.81.163:22: connect: connection refused
2019/08/02 16:56:05 packer: 2019/08/02 16:56:05 [INFO] Attempting SSH connection to 18.216.81.163:22...
2019/08/02 16:56:05 packer: 2019/08/02 16:56:05 [DEBUG] Config to &ssh.Config{SSHConfig:(*ssh.ClientConfig)(0xc0003a6f70), Connection:(func() (net.Conn, error))(0x1151950), Pty:false, DisableAgentForwarding:false, HandshakeTimeout:0, UseSftp:false, KeepAliveInterval:5000000000, Timeout:0}...
2019/08/02 16:56:05 packer: 2019/08/02 16:56:05 [DEBUG] reconnecting to TCP connection for SSH
2019/08/02 16:56:05 packer: 2019/08/02 16:56:05 [DEBUG] handshaking with SSH
2019/08/02 16:56:06 packer: 2019/08/02 16:56:06 [DEBUG] handshake complete!
2019/08/02 16:56:06 packer: 2019/08/02 16:56:06 [INFO] no local agent socket, will not connect agent
2019/08/02 16:56:06 packer: 2019/08/02 16:56:06 Running the provision hook
2019-08-02T16:56:06Z: ==> amazon-ebs: Connected to SSH!
2019/08/02 16:56:06 [INFO] (telemetry) Starting provisioner ansible
2019-08-02T16:56:06Z: ==> amazon-ebs: Provisioning with Ansible...
2019/08/02 16:56:06 packer: 2019/08/02 16:56:06 SSH proxy: serving on 127.0.0.1:39595
2019-08-02T16:56:06Z: ==> amazon-ebs: Executing Ansible: ansible-playbook --extra-vars packer_build_name=amazon-ebs packer_builder_type=amazon-ebs -o IdentitiesOnly=yes -i /tmp/packer-provisioner-ansible182928855 /demo/playbook.yml -e ansible_ssh_private_key_file=/tmp/ansible-key266559064 -vvvv
2019-08-02T16:56:06Z:     amazon-ebs: ansible-playbook 2.8.3
2019-08-02T16:56:06Z:     amazon-ebs:   config file = /etc/ansible/ansible.cfg
2019-08-02T16:56:06Z:     amazon-ebs:   configured module search path = [u'/home/not-a-root-user/.ansible/plugins/modules', u'/usr/share/ansible/plugins/modules']
2019-08-02T16:56:06Z:     amazon-ebs:   ansible python module location = /usr/lib/python2.7/site-packages/ansible
2019-08-02T16:56:06Z:     amazon-ebs:   executable location = /usr/bin/ansible-playbook
2019-08-02T16:56:06Z:     amazon-ebs:   python version = 2.7.5 (default, Oct 30 2018, 23:45:53) [GCC 4.8.5 20150623 (Red Hat 4.8.5-36)]
2019-08-02T16:56:06Z:     amazon-ebs: Using /etc/ansible/ansible.cfg as config file
2019-08-02T16:56:06Z:     amazon-ebs: setting up inventory plugins
2019-08-02T16:56:06Z:     amazon-ebs: host_list declined parsing /tmp/packer-provisioner-ansible182928855 as it did not pass it's verify_file() method
2019-08-02T16:56:06Z:     amazon-ebs: script declined parsing /tmp/packer-provisioner-ansible182928855 as it did not pass it's verify_file() method
2019-08-02T16:56:06Z:     amazon-ebs: auto declined parsing /tmp/packer-provisioner-ansible182928855 as it did not pass it's verify_file() method
2019-08-02T16:56:06Z:     amazon-ebs: Parsed /tmp/packer-provisioner-ansible182928855 inventory source with ini plugin
2019-08-02T16:56:06Z:     amazon-ebs: Loading callback plugin default of type stdout, v2.0 from /usr/lib/python2.7/site-packages/ansible/plugins/callback/default.pyc
2019-08-02T16:56:06Z:     amazon-ebs:
2019-08-02T16:56:06Z:     amazon-ebs: PLAYBOOK: playbook.yml *********************************************************
2019-08-02T16:56:06Z:     amazon-ebs: Positional arguments: /demo/playbook.yml
2019-08-02T16:56:06Z:     amazon-ebs: become_method: sudo
2019-08-02T16:56:06Z:     amazon-ebs: inventory: (u'/tmp/packer-provisioner-ansible182928855',)
2019-08-02T16:56:06Z:     amazon-ebs: forks: 5
2019-08-02T16:56:06Z:     amazon-ebs: tags: (u'all',)
2019-08-02T16:56:06Z:     amazon-ebs: extra_vars: (u'packer_build_name=amazon-ebs packer_builder_type=amazon-ebs -o IdentitiesOnly=yes', u'ansible_ssh_private_key_file=/tmp/ansible-key266559064')
2019-08-02T16:56:06Z:     amazon-ebs: verbosity: 4
2019-08-02T16:56:06Z:     amazon-ebs: connection: smart
2019-08-02T16:56:06Z:     amazon-ebs: timeout: 10
2019-08-02T16:56:06Z:     amazon-ebs: 1 plays in /demo/playbook.yml
2019-08-02T16:56:06Z:     amazon-ebs:
2019-08-02T16:56:06Z:     amazon-ebs: PLAY [all] *********************************************************************
2019-08-02T16:56:07Z:     amazon-ebs: META: ran handlers
2019-08-02T16:56:07Z:     amazon-ebs:
2019-08-02T16:56:07Z:     amazon-ebs: TASK [uri] *********************************************************************
2019-08-02T16:56:07Z:     amazon-ebs: task path: /demo/playbook.yml:5
2019-08-02T16:56:07Z:     amazon-ebs: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: not-a-root-user
2019-08-02T16:56:07Z:     amazon-ebs: <127.0.0.1> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=39595 -o 'IdentityFile="/tmp/ansible-key266559064"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="not-a-root-user"' -o ConnectTimeout=10 -o ControlPath=/home/not-a-root-user/.ansible/cp/dce6f73f06 127.0.0.1 '/bin/sh -c '"'"'echo ~not-a-root-user && sleep 0'"'"''
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 SSH proxy: accepted connection
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 authentication attempt from 127.0.0.1:50336 to 127.0.0.1:39595 as not-a-root-user using none
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 authentication attempt from 127.0.0.1:50336 to 127.0.0.1:39595 as not-a-root-user using publickey
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 new env request: LC_ALL=en_US.UTF-8
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 new exec request: /bin/sh -c 'echo ~not-a-root-user && sleep 0'
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 [DEBUG] Opening new ssh session
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 [DEBUG] starting remote command: /bin/sh -c 'echo ~not-a-root-user && sleep 0'
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:07 [INFO] 17 bytes written for 'stdout'
2019/08/02 16:56:07 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:07 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:07 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:07 [INFO] 0 bytes written for 'stdin'
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 [INFO] 17 bytes written for 'stdout'
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:07 packer: 2019/08/02 16:56:07 [INFO] 0 bytes written for 'stdin'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 new env request: LC_ALL=en_US.UTF-8
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo ~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290 `" && echo ansible-tmp-1564764967.05-149392736356290="` echo ~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290 `" ) && sleep 0'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [DEBUG] Opening new ssh session
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [DEBUG] starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo ~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290 `" && echo ansible-tmp-1564764967.05-149392736356290="` echo ~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290 `" ) && sleep 0'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:08 [INFO] 114 bytes written for 'stdout'
2019/08/02 16:56:08 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:08 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:08 [INFO] 0 bytes written for 'stdin'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] 114 bytes written for 'stdout'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] 0 bytes written for 'stdin'
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> (0, '~not-a-root-user\n', 'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug1: Control socket "/home/not-a-root-user/.ansible/cp/dce6f73f06" does not exist\r\ndebug2: resolving "127.0.0.1" port 39595\r\ndebug2: ssh_connect_direct: needpriv 0\r\ndebug1: Connecting to 127.0.0.1 [127.0.0.1] port 39595.\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug1: fd 3 clearing O_NONBLOCK\r\ndebug1: Connection established.\r\ndebug3: timeout: 10000 ms remain after connect\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /tmp/ansible-key266559064 type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /tmp/ansible-key266559064-cert type -1\r\ndebug1: Enabling compatibility mode for protocol 2.0\r\ndebug1: Local version string SSH-2.0-OpenSSH_7.4\r\ndebug1: Remote protocol version 2.0, remote software version Go\r\ndebug1: no match: Go\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug1: Authenticating to 127.0.0.1:39595 as \'not-a-root-user\'\r\ndebug3: put_host_port: [127.0.0.1]:39595\r\ndebug3: hostkeys_foreach: reading file "/home/not-a-root-user/.ssh/known_hosts"\r\ndebug3: send packet: type 20\r\ndebug1: SSH2_MSG_KEXINIT sent\r\ndebug3: receive packet: type 20\r\ndebug1: SSH2_MSG_KEXINIT received\r\ndebug2: local client KEXINIT proposal\r\ndebug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1,ext-info-c\r\ndebug2: host key algorithms: ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ssh-ed25519-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,rsa-sha2-512,rsa-sha2-256,ssh-rsa,ssh-dss\r\ndebug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc\r\ndebug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc\r\ndebug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1\r\ndebug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1\r\ndebug2: compression ctos: zlib@openssh.com,zlib,none\r\ndebug2: compression stoc: zlib@openssh.com,zlib,none\r\ndebug2: languages ctos: \r\ndebug2: languages stoc: \r\ndebug2: first_kex_follows 0 \r\ndebug2: reserved 0 \r\ndebug2: peer server KEXINIT proposal\r\ndebug2: KEX algorithms: curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1\r\ndebug2: host key algorithms: ssh-rsa\r\ndebug2: ciphers ctos: aes128-gcm@openssh.com,chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr\r\ndebug2: ciphers stoc: aes128-gcm@openssh.com,chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr\r\ndebug2: MACs ctos: hmac-sha2-256-etm@openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha1-96\r\ndebug2: MACs stoc: hmac-sha2-256-etm@openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha1-96\r\ndebug2: compression ctos: none\r\ndebug2: compression stoc: none\r\ndebug2: languages ctos: \r\ndebug2: languages stoc: \r\ndebug2: first_kex_follows 0 \r\ndebug2: reserved 0 \r\ndebug1: kex: algorithm: curve25519-sha256@libssh.org\r\ndebug1: kex: host key algorithm: ssh-rsa\r\ndebug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: none\r\ndebug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: none\r\ndebug1: kex: curve25519-sha256@libssh.org need=64 dh_need=64\r\ndebug1: kex: curve25519-sha256@libssh.org need=64 dh_need=64\r\ndebug3: send packet: type 30\r\ndebug1: expecting SSH2_MSG_KEX_ECDH_REPLY\r\ndebug3: receive packet: type 31\r\ndebug1: Server host key: ssh-rsa SHA256:da/yGXWM9gFvqaEeDH7VerQVUGLNCK9i+NOaBkwEt5w\r\ndebug3: put_host_port: [127.0.0.1]:39595\r\ndebug3: put_host_port: [127.0.0.1]:39595\r\ndebug3: hostkeys_foreach: reading file "/home/not-a-root-user/.ssh/known_hosts"\r\ndebug1: checking without port identifier\r\ndebug3: hostkeys_foreach: reading file "/home/not-a-root-user/.ssh/known_hosts"\r\nWarning: Permanently added \'[127.0.0.1]:39595\' (RSA) to the list of known hosts.\r\ndebug3: send packet: type 21\r\ndebug2: set_newkeys: mode 1\r\ndebug1: rekey after 134217728 blocks\r\ndebug1: SSH2_MSG_NEWKEYS sent\r\ndebug1: expecting SSH2_MSG_NEWKEYS\r\ndebug3: receive packet: type 21\r\ndebug1: SSH2_MSG_NEWKEYS received\r\ndebug2: set_newkeys: mode 0\r\ndebug1: rekey after 134217728 blocks\r\ndebug2: key: /tmp/ansible-key266559064 ((nil)), explicit\r\ndebug3: send packet: type 5\r\ndebug3: receive packet: type 6\r\ndebug2: service_accept: ssh-userauth\r\ndebug1: SSH2_MSG_SERVICE_ACCEPT received\r\ndebug3: send packet: type 50\r\ndebug3: receive packet: type 51\r\ndebug1: Authentications that can continue: publickey\r\ndebug3: start over, passed a different list publickey\r\ndebug3: preferred gssapi-with-mic,gssapi-keyex,hostbased,publickey\r\ndebug3: authmethod_lookup publickey\r\ndebug3: remaining preferred: ,gssapi-keyex,hostbased,publickey\r\ndebug3: authmethod_is_enabled publickey\r\ndebug1: Next authentication method: publickey\r\ndebug1: Trying private key: /tmp/ansible-key266559064\r\ndebug3: sign_and_send_pubkey: RSA SHA256:gGWwAjdZuJxZwwH5kP34mWRE66k12VhvRCHcLcs0eao\r\ndebug3: send packet: type 50\r\ndebug2: we sent a publickey packet, wait for reply\r\ndebug3: receive packet: type 52\r\ndebug1: Authentication succeeded (publickey).\r\nAuthenticated to 127.0.0.1 ([127.0.0.1]:39595).\r\ndebug1: setting up multiplex master socket\r\ndebug3: muxserver_listen: temporary control path /home/not-a-root-user/.ansible/cp/dce6f73f06.rmnisA0Pnb1cjbel\r\ndebug2: fd 4 setting O_NONBLOCK\r\ndebug3: fd 4 is O_NONBLOCK\r\ndebug3: fd 4 is O_NONBLOCK\r\ndebug1: channel 0: new [/home/not-a-root-user/.ansible/cp/dce6f73f06]\r\ndebug3: muxserver_listen: mux listener channel 0 fd 4\r\ndebug2: fd 3 setting TCP_NODELAY\r\ndebug3: ssh_packet_set_tos: set IP_TOS 0x08\r\ndebug1: control_persist_detach: backgrounding master process\r\ndebug2: control_persist_detach: background process is 15223\r\ndebug2: fd 4 setting O_NONBLOCK\r\ndebug1: forking to background\r\ndebug1: Entering interactive session.\r\ndebug1: pledge: id\r\ndebug2: set_control_persist_exit_time: schedule exit in 60 seconds\r\ndebug1: multiplexing control connection\r\ndebug2: fd 5 setting O_NONBLOCK\r\ndebug3: fd 5 is O_NONBLOCK\r\ndebug1: channel 1: new [mux-control]\r\ndebug3: channel_post_mux_listener: new mux channel 1 fd 5\r\ndebug3: mux_master_read_cb: channel 1: hello sent\r\ndebug2: set_control_persist_exit_time: cancel scheduled exit\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x00000001 len 4\r\ndebug2: process_mux_master_hello: channel 1 slave version 4\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x10000004 len 4\r\ndebug2: process_mux_alive_check: channel 1: alive check\r\ndebug3: mux_client_request_alive: done pid = 15225\r\ndebug3: mux_client_request_session: session request sent\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x10000002 len 108\r\ndebug2: process_mux_new_session: channel 1: request tty 0, X 0, agent 0, subsys 0, term "xterm", cmd "/bin/sh -c \'echo ~not-a-root-user && sleep 0\'", env 1\r\ndebug3: process_mux_new_session: got fds stdin 6, stdout 7, stderr 8\r\ndebug2: fd 7 setting O_NONBLOCK\r\ndebug2: fd 8 setting O_NONBLOCK\r\ndebug1: channel 2: new [client-session]\r\ndebug2: process_mux_new_session: channel_new: 2 linked to control channel 1\r\ndebug2: channel 2: send open\r\ndebug3: send packet: type 90\r\ndebug3: receive packet: type 91\r\ndebug2: callback start\r\ndebug2: client_session2_setup: id 2\r\ndebug1: Sending environment.\r\ndebug1: Sending env LC_ALL = en_US.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending command: /bin/sh -c \'echo ~not-a-root-user && sleep 0\'\r\ndebug2: channel 2: request exec confirm 1\r\ndebug3: send packet: type 98\r\ndebug3: mux_session_confirm: sending success reply\r\ndebug2: callback done\r\ndebug2: channel 2: open confirm rwindow 2097152 rmax 32768\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: receive packet: type 99\r\ndebug2: channel_input_status_confirm: type 99 id 2\r\ndebug2: exec request accepted on channel 2\r\ndebug3: receive packet: type 98\r\ndebug1: client_input_channel_req: channel 2 rtype exit-status reply 0\r\ndebug3: mux_exit_message: channel 2: exit message, exitval 0\r\ndebug3: receive packet: type 97\r\ndebug2: channel 2: rcvd close\r\ndebug2: channel 2: output open -> drain\r\ndebug2: channel 2: close_read\r\ndebug2: channel 2: input open -> closed\r\ndebug3: channel 2: will not send data after close\r\ndebug2: channel 2: obuf empty\r\ndebug2: channel 2: close_write\r\ndebug2: channel 2: output drain -> closed\r\ndebug2: channel 2: send close\r\ndebug3: send packet: type 97\r\ndebug2: channel 2: is dead\r\ndebug2: channel 2: gc: notify user\r\ndebug3: mux_master_session_cleanup_cb: entering for channel 2\r\ndebug2: channel 1: rcvd close\r\ndebug2: channel 1: output open -> drain\r\ndebug2: channel 1: close_read\r\ndebug2: channel 1: input open -> closed\r\ndebug2: channel 2: gc: user detached\r\ndebug2: channel 2: is dead\r\ndebug2: channel 2: garbage collecting\r\ndebug1: channel 2: free: client-session, nchannels 3\r\ndebug3: channel 2: status: The following connections are open:\r\n  #1 mux-control (t16 r-1 i3/0 o1/16 fd 5/5 cc -1)\r\n  #2 client-session (t4 r0 i3/0 o3/0 fd -1/-1 cc -1)\r\n\r\ndebug2: channel 1: obuf empty\r\ndebug2: channel 1: close_write\r\ndebug2: channel 1: output drain -> closed\r\ndebug2: channel 1: is dead (local)\r\ndebug2: channel 1: gc: notify user\r\ndebug3: mux_master_control_cleanup_cb: entering for channel 1\r\ndebug2: channel 1: gc: user detached\r\ndebug2: channel 1: is dead (local)\r\ndebug2: channel 1: garbage collecting\r\ndebug1: channel 1: free: mux-control, nchannels 2\r\ndebug3: channel 1: status: The following connections are open:\r\n  #1 mux-control (t16 r-1 i3/0 o3/0 fd 5/5 cc -1)\r\n\r\ndebug2: set_control_persist_exit_time: schedule exit in 60 seconds\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: not-a-root-user
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=39595 -o 'IdentityFile="/tmp/ansible-key266559064"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="not-a-root-user"' -o ConnectTimeout=10 -o ControlPath=/home/not-a-root-user/.ansible/cp/dce6f73f06 127.0.0.1 '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo ~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290 `" && echo ansible-tmp-1564764967.05-149392736356290="` echo ~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290 `" ) && sleep 0'"'"''
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> (0, 'ansible-tmp-1564764967.05-149392736356290=~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290\n', 'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 15225\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
2019-08-02T16:56:08Z:     amazon-ebs: <default> Attempting python interpreter discovery
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: not-a-root-user
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=39595 -o 'IdentityFile="/tmp/ansible-key266559064"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="not-a-root-user"' -o ConnectTimeout=10 -o ControlPath=/home/not-a-root-user/.ansible/cp/dce6f73f06 127.0.0.1 '/bin/sh -c '"'"'echo PLATFORM; uname; echo FOUND; command -v '"'"'"'"'"'"'"'"'/usr/bin/python'"'"'"'"'"'"'"'"'; command -v '"'"'"'"'"'"'"'"'python3.7'"'"'"'"'"'"'"'"'; command -v '"'"'"'"'"'"'"'"'python3.6'"'"'"'"'"'"'"'"'; command -v '"'"'"'"'"'"'"'"'python3.5'"'"'"'"'"'"'"'"'; command -v '"'"'"'"'"'"'"'"'python2.7'"'"'"'"'"'"'"'"'; command -v '"'"'"'"'"'"'"'"'python2.6'"'"'"'"'"'"'"'"'; command -v '"'"'"'"'"'"'"'"'/usr/libexec/platform-python'"'"'"'"'"'"'"'"'; command -v '"'"'"'"'"'"'"'"'/usr/bin/python3'"'"'"'"'"'"'"'"'; command -v '"'"'"'"'"'"'"'"'python'"'"'"'"'"'"'"'"'; echo ENDFOUND && sleep 0'"'"''
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 new env request: LC_ALL=en_US.UTF-8
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 new exec request: /bin/sh -c 'echo PLATFORM; uname; echo FOUND; command -v '"'"'/usr/bin/python'"'"'; command -v '"'"'python3.7'"'"'; command -v '"'"'python3.6'"'"'; command -v '"'"'python3.5'"'"'; command -v '"'"'python2.7'"'"'; command -v '"'"'python2.6'"'"'; command -v '"'"'/usr/libexec/platform-python'"'"'; command -v '"'"'/usr/bin/python3'"'"'; command -v '"'"'python'"'"'; echo ENDFOUND && sleep 0'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [DEBUG] Opening new ssh session
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [DEBUG] starting remote command: /bin/sh -c 'echo PLATFORM; uname; echo FOUND; command -v '"'"'/usr/bin/python'"'"'; command -v '"'"'python3.7'"'"'; command -v '"'"'python3.6'"'"'; command -v '"'"'python3.5'"'"'; command -v '"'"'python2.7'"'"'; command -v '"'"'python2.6'"'"'; command -v '"'"'/usr/libexec/platform-python'"'"'; command -v '"'"'/usr/bin/python3'"'"'; command -v '"'"'python'"'"'; echo ENDFOUND && sleep 0'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:08 [INFO] 110 bytes written for 'stdout'
2019/08/02 16:56:08 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:08 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:08 [INFO] 0 bytes written for 'stdin'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] 110 bytes written for 'stdout'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] 0 bytes written for 'stdin'
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> (0, 'PLATFORM\nLinux\nFOUND\n/usr/bin/python\n/usr/bin/python2.7\n/usr/libexec/platform-python\n/usr/bin/python\nENDFOUND\n', 'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 15225\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: not-a-root-user
2019-08-02T16:56:08Z:     amazon-ebs: <127.0.0.1> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=39595 -o 'IdentityFile="/tmp/ansible-key266559064"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="not-a-root-user"' -o ConnectTimeout=10 -o ControlPath=/home/not-a-root-user/.ansible/cp/dce6f73f06 127.0.0.1 '/bin/sh -c '"'"'/usr/bin/python && sleep 0'"'"''
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 new env request: LC_ALL=en_US.UTF-8
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 new exec request: /bin/sh -c '/usr/bin/python && sleep 0'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [INFO] 1234 bytes written for 'stdin'
2019/08/02 16:56:08 [INFO] 1234 bytes written for 'stdin'
2019/08/02 16:56:08 packer: 2019/08/02 16:56:08 [DEBUG] Opening new ssh session
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 [DEBUG] starting remote command: /bin/sh -c '/usr/bin/python && sleep 0'
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:09 [INFO] 519 bytes written for 'stdout'
2019/08/02 16:56:09 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:09 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 [INFO] 519 bytes written for 'stdout'
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 [INFO] RPC client: Communicator ended with: 0
2019-08-02T16:56:09Z:     amazon-ebs: <127.0.0.1> (0, '{"osrelease_content": "NAME=\\"CentOS Linux\\"\\nVERSION=\\"7 (Core)\\"\\nID=\\"centos\\"\\nID_LIKE=\\"rhel fedora\\"\\nVERSION_ID=\\"7\\"\\nPRETTY_NAME=\\"CentOS Linux 7 (Core)\\"\\nANSI_COLOR=\\"0;31\\"\\nCPE_NAME=\\"cpe:/o:centos:centos:7\\"\\nHOME_URL=\\"https://www.centos.org/\\"\\nBUG_REPORT_URL=\\"https://bugs.centos.org/\\"\\n\\nCENTOS_MANTISBT_PROJECT=\\"CentOS-7\\"\\nCENTOS_MANTISBT_PROJECT_VERSION=\\"7\\"\\nREDHAT_SUPPORT_PRODUCT=\\"centos\\"\\nREDHAT_SUPPORT_PRODUCT_VERSION=\\"7\\"\\n\\n", "platform_dist_result": ["centos", "7.6.1810", "Core"]}\n', 'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 15225\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
2019-08-02T16:56:09Z:     amazon-ebs: Using module file /usr/lib/python2.7/site-packages/ansible/modules/net_tools/basics/uri.py
2019-08-02T16:56:09Z:     amazon-ebs: Pipelining is enabled.
2019-08-02T16:56:09Z:     amazon-ebs: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: not-a-root-user
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 new env request: LC_ALL=en_US.UTF-8
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 new exec request: /bin/sh -c 'sudo -H -S -n  -u root /bin/sh -c '"'"'echo BECOME-SUCCESS-gbyuoaxrfpayxjhqzdfbqpxpusujxljs ; /usr/bin/python'"'"' && sleep 0'
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 [DEBUG] Opening new ssh session
2019-08-02T16:56:09Z:     amazon-ebs: <127.0.0.1> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=39595 -o 'IdentityFile="/tmp/ansible-key266559064"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="not-a-root-user"' -o ConnectTimeout=10 -o ControlPath=/home/not-a-root-user/.ansible/cp/dce6f73f06 127.0.0.1 '/bin/sh -c '"'"'sudo -H -S -n  -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-gbyuoaxrfpayxjhqzdfbqpxpusujxljs ; /usr/bin/python'"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 [DEBUG] starting remote command: /bin/sh -c 'sudo -H -S -n  -u root /bin/sh -c '"'"'echo BECOME-SUCCESS-gbyuoaxrfpayxjhqzdfbqpxpusujxljs ; /usr/bin/python'"'"' && sleep 0'
2019-08-02T16:56:09Z:     amazon-ebs: Escalation succeeded
2019/08/02 16:56:09 [INFO] 132031 bytes written for 'stdin'
2019/08/02 16:56:09 packer: 2019/08/02 16:56:09 [INFO] 132031 bytes written for 'stdin'
2019/08/02 16:56:10 [INFO] 1583 bytes written for 'stdout'
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:10 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:10 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] 1583 bytes written for 'stdout'
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 new env request: LC_ALL=en_US.UTF-8
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 new exec request: /bin/sh -c 'rm -f -r '"'"'~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290/'"'"' > /dev/null 2>&1 && sleep 0'
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [DEBUG] Opening new ssh session
2019-08-02T16:56:10Z:     amazon-ebs: <127.0.0.1> (0, '\n{"status": 200, "cookies": {"__cfduid": "d3340908cc00479b57ee5d2eb82e4a84c1564764970"}, "via": "1.1 vegur", "set_cookie": "__cfduid=d3340908cc00479b57ee5d2eb82e4a84c1564764970; expires=Sat, 01-Aug-20 16:56:10 GMT; path=/; domain=.ifconfig.co; HttpOnly", "url": "https://ifconfig.co/", "transfer_encoding": "chunked", "changed": false, "cf_ray": "50019568fe26caac-YYZ", "expect_ct": "max-age=604800, report-uri=\\"https://report-uri.cloudflare.com/cdn-cgi/beacon/expect-ct\\"", "server": "cloudflare", "invocation": {"module_args": {"directory_mode": null, "force": false, "remote_src": null, "status_code": [200], "body_format": "raw", "owner": null, "follow": false, "client_key": null, "group": null, "use_proxy": true, "headers": {}, "unsafe_writes": null, "serole": null, "content": null, "setype": null, "follow_redirects": "safe", "return_content": false, "client_cert": null, "body": null, "timeout": 30, "src": null, "dest": null, "selevel": null, "force_basic_auth": false, "removes": null, "http_agent": "ansible-httpget", "regexp": null, "url_password": null, "url": "https://ifconfig.co/", "validate_certs": true, "seuser": null, "method": "GET", "creates": null, "unix_socket": null, "delimiter": null, "mode": null, "url_username": null, "attributes": null, "backup": null}}, "connection": "close", "content_type": "text/html; charset=utf-8", "date": "Fri, 02 Aug 2019 16:56:10 GMT", "redirected": false, "elapsed": 0, "cookies_string": "__cfduid=d3340908cc00479b57ee5d2eb82e4a84c1564764970", "msg": "OK (unknown bytes)"}\n', 'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 15225\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
2019-08-02T16:56:10Z:     amazon-ebs: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: not-a-root-user
2019-08-02T16:56:10Z:     amazon-ebs: <127.0.0.1> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=39595 -o 'IdentityFile="/tmp/ansible-key266559064"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="not-a-root-user"' -o ConnectTimeout=10 -o ControlPath=/home/not-a-root-user/.ansible/cp/dce6f73f06 127.0.0.1 '/bin/sh -c '"'"'rm -f -r '"'"'"'"'"'"'"'"'~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290/'"'"'"'"'"'"'"'"' > /dev/null 2>&1 && sleep 0'"'"''
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [DEBUG] starting remote command: /bin/sh -c 'rm -f -r '"'"'~not-a-root-user/.ansible/tmp/ansible-tmp-1564764967.05-149392736356290/'"'"' > /dev/null 2>&1 && sleep 0'
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:10 [INFO] 0 bytes written for 'stdout'
2019/08/02 16:56:10 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:10 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:56:10 [INFO] 0 bytes written for 'stdin'
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] 0 bytes written for 'stdout'
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 [INFO] 0 bytes written for 'stdin'
2019-08-02T16:56:10Z:     amazon-ebs: <127.0.0.1> (0, '', 'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 15225\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
2019-08-02T16:56:10Z:     amazon-ebs: ok: [default] => {
2019-08-02T16:56:10Z:     amazon-ebs:     "ansible_facts": {
2019-08-02T16:56:10Z:     amazon-ebs:         "discovered_interpreter_python": "/usr/bin/python"
2019-08-02T16:56:10Z:     amazon-ebs:     },
2019-08-02T16:56:10Z:     amazon-ebs:     "cf_ray": "50019568fe26caac-YYZ",
2019-08-02T16:56:10Z:     amazon-ebs:     "changed": false,
2019-08-02T16:56:10Z:     amazon-ebs:     "connection": "close",
2019-08-02T16:56:10Z:     amazon-ebs:     "content_type": "text/html; charset=utf-8",
2019-08-02T16:56:10Z:     amazon-ebs:     "cookies": {
2019-08-02T16:56:10Z:     amazon-ebs:         "__cfduid": "d3340908cc00479b57ee5d2eb82e4a84c1564764970"
2019-08-02T16:56:10Z:     amazon-ebs:     },
2019-08-02T16:56:10Z:     amazon-ebs:     "cookies_string": "__cfduid=d3340908cc00479b57ee5d2eb82e4a84c1564764970",
2019-08-02T16:56:10Z:     amazon-ebs:     "date": "Fri, 02 Aug 2019 16:56:10 GMT",
2019-08-02T16:56:10Z:     amazon-ebs:     "elapsed": 0,
2019-08-02T16:56:10Z:     amazon-ebs:     "expect_ct": "max-age=604800, report-uri=\"https://report-uri.cloudflare.com/cdn-cgi/beacon/expect-ct\"",
2019-08-02T16:56:10Z:     amazon-ebs:     "invocation": {
2019-08-02T16:56:10Z:     amazon-ebs:         "module_args": {
2019-08-02T16:56:10Z:     amazon-ebs:             "attributes": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "backup": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "body": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "body_format": "raw",
2019-08-02T16:56:10Z:     amazon-ebs:             "client_cert": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "client_key": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "content": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "creates": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "delimiter": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "dest": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "directory_mode": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "follow": false,
2019-08-02T16:56:10Z:     amazon-ebs:             "follow_redirects": "safe",
2019-08-02T16:56:10Z:     amazon-ebs:             "force": false,
2019-08-02T16:56:10Z:     amazon-ebs:             "force_basic_auth": false,
2019-08-02T16:56:10Z:     amazon-ebs:             "group": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "headers": {},
2019-08-02T16:56:10Z:     amazon-ebs:             "http_agent": "ansible-httpget",
2019-08-02T16:56:10Z:     amazon-ebs:             "method": "GET",
2019-08-02T16:56:10Z:     amazon-ebs:             "mode": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "owner": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "regexp": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "remote_src": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "removes": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "return_content": false,
2019-08-02T16:56:10Z:     amazon-ebs:             "selevel": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "serole": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "setype": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "seuser": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "src": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "status_code": [
2019-08-02T16:56:10Z:     amazon-ebs:                 200
2019-08-02T16:56:10Z:     amazon-ebs:             ],
2019-08-02T16:56:10Z:     amazon-ebs:             "timeout": 30,
2019-08-02T16:56:10Z:     amazon-ebs:             "unix_socket": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "unsafe_writes": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "url": "https://ifconfig.co/",
2019-08-02T16:56:10Z:     amazon-ebs:             "url_password": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "url_username": null,
2019-08-02T16:56:10Z:     amazon-ebs:             "use_proxy": true,
2019-08-02T16:56:10Z:     amazon-ebs:             "validate_certs": true
2019-08-02T16:56:10Z:     amazon-ebs:         }
2019-08-02T16:56:10Z:     amazon-ebs:     },
2019-08-02T16:56:10Z:     amazon-ebs:     "msg": "OK (unknown bytes)",
2019-08-02T16:56:10Z:     amazon-ebs:     "redirected": false,
2019-08-02T16:56:10Z:     amazon-ebs:     "server": "cloudflare",
2019-08-02T16:56:10Z:     amazon-ebs:     "set_cookie": "__cfduid=d3340908cc00479b57ee5d2eb82e4a84c1564764970; expires=Sat, 01-Aug-20 16:56:10 GMT; path=/; domain=.ifconfig.co; HttpOnly",
2019-08-02T16:56:10Z:     amazon-ebs:     "status": 200,
2019-08-02T16:56:10Z:     amazon-ebs:     "transfer_encoding": "chunked",
2019-08-02T16:56:10Z:     amazon-ebs:     "url": "https://ifconfig.co/",
2019-08-02T16:56:10Z:     amazon-ebs:     "via": "1.1 vegur"
2019-08-02T16:56:10Z:     amazon-ebs: }
2019-08-02T16:56:10Z:     amazon-ebs: META: ran handlers
2019-08-02T16:56:10Z:     amazon-ebs: META: ran handlers
2019-08-02T16:56:10Z:     amazon-ebs:
2019-08-02T16:56:10Z:     amazon-ebs: PLAY RECAP *********************************************************************
2019-08-02T16:56:10Z:     amazon-ebs: default                    : ok=1    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2019-08-02T16:56:10Z:     amazon-ebs:
2019/08/02 16:56:10 packer: 2019/08/02 16:56:10 shutting down the SSH proxy
2019/08/02 16:56:10 [INFO] (telemetry) ending ansible
2019-08-02T16:56:10Z: ==> amazon-ebs: Stopping the source instance...
2019-08-02T16:56:10Z:     amazon-ebs: Stopping instance
2019-08-02T16:56:11Z: ==> amazon-ebs: Waiting for the instance to stop...
2019-08-02T16:56:42Z: ==> amazon-ebs: Creating AMI ansible-root-bug 2019-08-02T16-54-39Z from instance i-0dc5fd2f8a0d7c4d5
2019-08-02T16:56:42Z:     amazon-ebs: AMI: ami-0d08ed4afc80c6eeb
2019-08-02T16:56:42Z: ==> amazon-ebs: Waiting for AMI to become ready...
2019-08-02T16:57:28Z: ==> amazon-ebs: Terminating the source AWS instance...
2019-08-02T16:57:44Z: ==> amazon-ebs: Cleaning up any extra volumes...
2019-08-02T16:57:44Z: ==> amazon-ebs: Destroying volume (vol-0ca3333863027c053)...
2019-08-02T16:57:44Z: ==> amazon-ebs: Deleting temporary security group...
2019-08-02T16:57:45Z: ==> amazon-ebs: Deleting temporary keypair...
2019/08/02 16:57:45 [INFO] (telemetry) ending amazon-ebs
==> Builds finished. The artifacts of successful builds are:
2019/08/02 16:57:45 machine readable: amazon-ebs,artifact-count []string{"1"}
2019-08-02T16:57:45Z: Build 'amazon-ebs' finished.

==> Builds finished. The artifacts of successful builds are:
2019/08/02 16:57:45 machine readable: amazon-ebs,artifact []string{"0", "builder-id", "mitchellh.amazonebs"}
2019/08/02 16:57:45 machine readable: amazon-ebs,artifact []string{"0", "id", "us-east-2:ami-0d08ed4afc80c6eeb"}
2019/08/02 16:57:45 machine readable: amazon-ebs,artifact []string{"0", "string", "AMIs were created:\nus-east-2: ami-0d08ed4afc80c6eeb\n"}
2019/08/02 16:57:45 machine readable: amazon-ebs,artifact []string{"0", "files-count", "0"}
2019/08/02 16:57:45 machine readable: amazon-ebs,artifact []string{"0", "end"}
us-east-2: ami-0d08ed4afc80c6eeb
--> amazon-ebs: AMIs were created:
us-east-2: ami-0d08ed4afc80c6eeb

2019/08/02 16:57:45 [INFO] (telemetry) Finalizing.
2019/08/02 16:57:46 waiting for all plugin processes to complete...
2019/08/02 16:57:46 /packer: plugin process exited
2019/08/02 16:57:46 /packer: plugin process exited

packer.json

{
  "builders": [
    {
      "ami_name": "ansible-root-bug {{isotime|clean_resource_name}}",
      "instance_type": "t3.micro",
      "name": "centos-ami",
      "source_ami": "ami-0f2b4fc905b0bd1f1",
      "region": "us-east-2",
      "ssh_username": "centos",
      "type": "amazon-ebs"
    }
  ],
  "provisioners": [
    {
      "extra_arguments": [
        "-vvvv"
      ],
      "playbook_file": "./playbook.yml",
      "type": "ansible"
    }
  ]
}

playbook.yml

- hosts: all
  become: true
  gather_facts: false
  tasks:
    - uri:
        url: https://ifconfig.co/

root.log

2019/08/02 16:39:15 [INFO] Packer version: 1.4.2
2019/08/02 16:39:15 Packer Target OS/Arch: linux amd64
2019/08/02 16:39:15 Built with Go Version: go1.12.6
2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 Using internal plugin for virtualbox-ovf
2019/08/02 16:39:15 Using internal plugin for amazon-instance
2019/08/02 16:39:15 Using internal plugin for proxmox
2019/08/02 16:39:15 Using internal plugin for triton
2019/08/02 16:39:15 Using internal plugin for virtualbox-iso
2019/08/02 16:39:15 Using internal plugin for vagrant
2019/08/02 16:39:15 Using internal plugin for amazon-ebs
2019/08/02 16:39:15 Using internal plugin for amazon-ebsvolume
2019/08/02 16:39:15 Using internal plugin for parallels-iso
2019/08/02 16:39:15 Using internal plugin for scaleway
2019/08/02 16:39:15 Using internal plugin for amazon-ebssurrogate
2019/08/02 16:39:15 Using internal plugin for hyperv-vmcx
2019/08/02 16:39:15 Using internal plugin for lxd
2019/08/02 16:39:15 Using internal plugin for null
2019/08/02 16:39:15 Using internal plugin for parallels-pvm
2019/08/02 16:39:15 Using internal plugin for yandex
2019/08/02 16:39:15 Using internal plugin for azure-arm
2019/08/02 16:39:15 Using internal plugin for docker
2019/08/02 16:39:15 Using internal plugin for hyperv-iso
2019/08/02 16:39:15 Using internal plugin for oneandone
2019/08/02 16:39:15 Using internal plugin for cloudstack
2019/08/02 16:39:15 Using internal plugin for profitbricks
2019/08/02 16:39:15 Using internal plugin for qemu
2019/08/02 16:39:15 Using internal plugin for oracle-classic
2019/08/02 16:39:15 Using internal plugin for digitalocean
2019/08/02 16:39:15 Using internal plugin for hyperone
2019/08/02 16:39:15 Using internal plugin for linode
2019/08/02 16:39:15 Using internal plugin for lxc
2019/08/02 16:39:15 Using internal plugin for openstack
2019/08/02 16:39:15 Using internal plugin for alicloud-ecs
2019/08/02 16:39:15 Using internal plugin for file
2019/08/02 16:39:15 Using internal plugin for googlecompute
2019/08/02 16:39:15 Using internal plugin for ncloud
2019/08/02 16:39:15 Using internal plugin for vmware-iso
2019/08/02 16:39:15 Using internal plugin for vmware-vmx
2019/08/02 16:39:15 Using internal plugin for amazon-chroot
2019/08/02 16:39:15 Using internal plugin for hcloud
2019/08/02 16:39:15 Using internal plugin for oracle-oci
2019/08/02 16:39:15 Using internal plugin for tencentcloud-cvm
2019/08/02 16:39:15 Using internal plugin for shell-local
2019/08/02 16:39:15 Using internal plugin for windows-restart
2019/08/02 16:39:15 Using internal plugin for sleep
2019/08/02 16:39:15 Using internal plugin for ansible-local
2019/08/02 16:39:15 Using internal plugin for breakpoint
2019/08/02 16:39:15 Using internal plugin for file
2019/08/02 16:39:15 Using internal plugin for powershell
2019/08/02 16:39:15 Using internal plugin for windows-shell
2019/08/02 16:39:15 Using internal plugin for ansible
2019/08/02 16:39:15 Using internal plugin for puppet-masterless
2019/08/02 16:39:15 Using internal plugin for puppet-server
2019/08/02 16:39:15 Using internal plugin for shell
2019/08/02 16:39:15 Using internal plugin for salt-masterless
2019/08/02 16:39:15 Using internal plugin for chef-client
2019/08/02 16:39:15 Using internal plugin for chef-solo
2019/08/02 16:39:15 Using internal plugin for converge
2019/08/02 16:39:15 Using internal plugin for inspec
2019/08/02 16:39:15 Using internal plugin for alicloud-import
2019/08/02 16:39:15 Using internal plugin for amazon-import
2019/08/02 16:39:15 Using internal plugin for docker-push
2019/08/02 16:39:15 Using internal plugin for vagrant-cloud
2019/08/02 16:39:15 Using internal plugin for docker-import
2019/08/02 16:39:15 Using internal plugin for docker-save
2019/08/02 16:39:15 Using internal plugin for shell-local
2019/08/02 16:39:15 Using internal plugin for vsphere-template
2019/08/02 16:39:15 Using internal plugin for artifice
2019/08/02 16:39:15 Using internal plugin for compress
2019/08/02 16:39:15 Using internal plugin for digitalocean-import
2019/08/02 16:39:15 Using internal plugin for docker-tag
2019/08/02 16:39:15 Using internal plugin for googlecompute-export
2019/08/02 16:39:15 Using internal plugin for manifest
2019/08/02 16:39:15 Using internal plugin for vagrant
2019/08/02 16:39:15 Using internal plugin for checksum
2019/08/02 16:39:15 Using internal plugin for googlecompute-import
2019/08/02 16:39:15 Using internal plugin for vsphere
2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 Attempting to open config file: /root/.packerconfig
2019/08/02 16:39:15 [WARN] Config file doesn't exist: /root/.packerconfig
2019/08/02 16:39:15 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[alicloud-ecs:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-alicloud-ecs amazon-chroot:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-chroot amazon-ebs:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebs amazon-ebssurrogate:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebssurrogate amazon-ebsvolume:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebsvolume amazon-instance:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-instance azure-arm:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-arm cloudstack:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-cloudstack digitalocean:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-digitalocean docker:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-docker file:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-file googlecompute:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-googlecompute hcloud:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hcloud hyperone:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperone hyperv-iso:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-iso hyperv-vmcx:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-vmcx linode:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-linode lxc:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxc lxd:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxd ncloud:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-ncloud null:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-null oneandone:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oneandone openstack:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-openstack oracle-classic:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-classic oracle-oci:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-oci parallels-iso:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-iso parallels-pvm:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-pvm profitbricks:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-profitbricks proxmox:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-proxmox qemu:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-qemu scaleway:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-scaleway tencentcloud-cvm:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-tencentcloud-cvm triton:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-triton vagrant:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vagrant virtualbox-iso:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-iso virtualbox-ovf:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-ovf vmware-iso:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-iso vmware-vmx:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-vmx yandex:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-yandex] PostProcessors:map[alicloud-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-alicloud-import amazon-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-amazon-import artifice:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-artifice checksum:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-checksum compress:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-compress digitalocean-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-digitalocean-import docker-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-import docker-push:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-push docker-save:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-save docker-tag:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-tag googlecompute-export:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-export googlecompute-import:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-import manifest:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-manifest shell-local:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-shell-local vagrant:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant vagrant-cloud:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant-cloud vsphere:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere vsphere-template:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere-template] Provisioners:map[ansible:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible ansible-local:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible-local breakpoint:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-breakpoint chef-client:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-client chef-solo:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-solo converge:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-converge file:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-file inspec:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-inspec powershell:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-powershell puppet-masterless:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-masterless puppet-server:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-server salt-masterless:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-salt-masterless shell:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell shell-local:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell-local sleep:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-sleep windows-restart:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-restart windows-shell:/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-shell]}
2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 Setting cache directory: /demo/packer_cache
2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 Loading builder: amazon-ebs
2019/08/02 16:39:15 Plugin could not be found. Checking same directory as executable.
2019/08/02 16:39:15 Current exe path: /packer
2019/08/02 16:39:15 Creating plugin client for path: /packer
2019/08/02 16:39:15 Starting plugin: /packer []string{"/packer", "plugin", "packer-builder-amazon-ebs"}
2019/08/02 16:39:15 Waiting for RPC address for: /packer
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 [INFO] Packer version: 1.4.2
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Packer Target OS/Arch: linux amd64
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Built with Go Version: go1.12.6
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Attempting to open config file: /root/.packerconfig
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 [WARN] Config file doesn't exist: /root/.packerconfig
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Setting cache directory: /demo/packer_cache
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 args: []string{"packer-builder-amazon-ebs"}
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Plugin minimum port: 10000
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Plugin maximum port: 25000
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Plugin address: unix /tmp/packer-plugin620535866
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Waiting for connection...
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Serving a plugin connection...
2019/08/02 16:39:15 Loading provisioner: ansible
2019/08/02 16:39:15 Plugin could not be found. Checking same directory as executable.
2019/08/02 16:39:15 Current exe path: /packer
2019/08/02 16:39:15 Creating plugin client for path: /packer
2019/08/02 16:39:15 Starting plugin: /packer []string{"/packer", "plugin", "packer-provisioner-ansible"}
2019/08/02 16:39:15 Waiting for RPC address for: /packer
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 [INFO] Packer version: 1.4.2
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Packer Target OS/Arch: linux amd64
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Built with Go Version: go1.12.6
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Attempting to open config file: /root/.packerconfig
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 [WARN] Config file doesn't exist: /root/.packerconfig
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Detected home directory from env var: /root
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Setting cache directory: /demo/packer_cache
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 args: []string{"packer-provisioner-ansible"}
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Plugin minimum port: 10000
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Plugin maximum port: 25000
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Plugin address: unix /tmp/packer-plugin534319347
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Waiting for connection...
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Serving a plugin connection...
2019/08/02 16:39:15 Build debug mode: false
2019/08/02 16:39:15 Force build: false
2019/08/02 16:39:15 On error:
2019/08/02 16:39:15 Preparing build: amazon-ebs
amazon-ebs output will be in this color.

2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 ansible-playbook version: 2.8.3
2019/08/02 16:39:15 Waiting on builds to complete...
2019/08/02 16:39:15 Starting build run: amazon-ebs
2019/08/02 16:39:15 Running builder: amazon-ebs
2019/08/02 16:39:15 [INFO] (telemetry) Starting builder amazon-ebs
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 Found region us-east-2
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 [INFO] AWS Auth provider used: "SharedConfigCredentials: /root/.aws/credentials"
2019/08/02 16:39:15 packer: 2019/08/02 16:39:15 [INFO] (aws): No AWS timeout and polling overrides have been set. Packer will default to waiter-specific delays and timeouts. If you would like to customize the length of time between retries and max number of retries you may do so by setting the environment variables AWS_POLL_DELAY_SECONDS and AWS_MAX_ATTEMPTS to your desired values.
2019-08-02T16:39:15Z: ==> amazon-ebs: Prevalidating AMI Name: ansible-root-bug 2019-08-02T16-39-15Z
2019/08/02 16:39:16 packer: 2019/08/02 16:39:16 Using AMI Filters {
2019/08/02 16:39:16 packer:   ImageIds: ["ami-0f2b4fc905b0bd1f1"]
2019/08/02 16:39:16 packer: }
2019-08-02T16:39:16Z:     amazon-ebs: Found Image ID: ami-0f2b4fc905b0bd1f1
2019-08-02T16:39:16Z: ==> amazon-ebs: Creating temporary keypair: packer_5d446733-ce01-364e-64e7-bd64e969cf38
2019-08-02T16:39:16Z: ==> amazon-ebs: Creating temporary security group for this instance: packer_5d446734-9256-a01d-e47c-b947b38fa10f
2019/08/02 16:39:16 packer: 2019/08/02 16:39:16 [DEBUG] Waiting for temporary security group: sg-0648583c6f4515556
2019/08/02 16:39:17 packer: 2019/08/02 16:39:17 [DEBUG] Found security group sg-0648583c6f4515556
2019-08-02T16:39:17Z: ==> amazon-ebs: Authorizing access to port 22 from [0.0.0.0/0] in the temporary security groups...
2019-08-02T16:39:17Z: ==> amazon-ebs: Launching a source AWS instance...
2019-08-02T16:39:17Z: ==> amazon-ebs: Adding tags to source instance
2019-08-02T16:39:17Z:     amazon-ebs: Adding tag: "Name": "Packer Builder"
2019-08-02T16:39:18Z:     amazon-ebs: Instance ID: i-08e5d20a056b71e7a
2019-08-02T16:39:18Z: ==> amazon-ebs: Waiting for instance (i-08e5d20a056b71e7a) to become ready...
2019/08/02 16:39:34 packer: 2019/08/02 16:39:34 [INFO] Not using winrm communicator, skipping get password...
2019/08/02 16:39:34 packer: 2019/08/02 16:39:34 [INFO] Waiting for SSH, up to timeout: 5m0s
2019-08-02T16:39:34Z: ==> amazon-ebs: Using ssh communicator to connect: 13.59.25.145
2019-08-02T16:39:34Z: ==> amazon-ebs: Waiting for SSH to become available...
2019/08/02 16:39:45 packer: 2019/08/02 16:39:45 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:39:50 packer: 2019/08/02 16:39:50 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:39:55 packer: 2019/08/02 16:39:55 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:00 packer: 2019/08/02 16:40:00 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:05 packer: 2019/08/02 16:40:05 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:10 packer: 2019/08/02 16:40:10 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:15 packer: 2019/08/02 16:40:15 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:20 packer: 2019/08/02 16:40:20 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:25 packer: 2019/08/02 16:40:25 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:30 packer: 2019/08/02 16:40:30 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:36 packer: 2019/08/02 16:40:36 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 13.59.25.145:22: connect: connection refused
2019/08/02 16:40:41 packer: 2019/08/02 16:40:41 [INFO] Attempting SSH connection to 13.59.25.145:22...
2019/08/02 16:40:41 packer: 2019/08/02 16:40:41 [DEBUG] Config to &ssh.Config{SSHConfig:(*ssh.ClientConfig)(0xc0003f8820), Connection:(func() (net.Conn, error))(0x1151950), Pty:false, DisableAgentForwarding:false, HandshakeTimeout:0, UseSftp:false, KeepAliveInterval:5000000000, Timeout:0}...
2019/08/02 16:40:41 packer: 2019/08/02 16:40:41 [DEBUG] reconnecting to TCP connection for SSH
2019/08/02 16:40:41 packer: 2019/08/02 16:40:41 [DEBUG] handshaking with SSH
2019/08/02 16:40:42 packer: 2019/08/02 16:40:42 [DEBUG] handshake complete!
2019/08/02 16:40:42 packer: 2019/08/02 16:40:42 [INFO] no local agent socket, will not connect agent
2019/08/02 16:40:42 packer: 2019/08/02 16:40:42 Running the provision hook
2019-08-02T16:40:42Z: ==> amazon-ebs: Connected to SSH!
2019/08/02 16:40:42 [INFO] (telemetry) Starting provisioner ansible
2019-08-02T16:40:42Z: ==> amazon-ebs: Provisioning with Ansible...
2019/08/02 16:40:42 packer: 2019/08/02 16:40:42 SSH proxy: serving on 127.0.0.1:39171
2019-08-02T16:40:42Z: ==> amazon-ebs: Executing Ansible: ansible-playbook --extra-vars packer_build_name=amazon-ebs packer_builder_type=amazon-ebs -o IdentitiesOnly=yes -i /tmp/packer-provisioner-ansible761946525 /demo/playbook.yml -e ansible_ssh_private_key_file=/tmp/ansible-key651216822 -vvvv
2019-08-02T16:40:43Z:     amazon-ebs: ansible-playbook 2.8.3
2019-08-02T16:40:43Z:     amazon-ebs:   config file = /etc/ansible/ansible.cfg
2019-08-02T16:40:43Z:     amazon-ebs:   configured module search path = [u'/root/.ansible/plugins/modules', u'/usr/share/ansible/plugins/modules']
2019-08-02T16:40:43Z:     amazon-ebs:   ansible python module location = /usr/lib/python2.7/site-packages/ansible
2019-08-02T16:40:43Z:     amazon-ebs:   executable location = /usr/bin/ansible-playbook
2019-08-02T16:40:43Z:     amazon-ebs:   python version = 2.7.5 (default, Oct 30 2018, 23:45:53) [GCC 4.8.5 20150623 (Red Hat 4.8.5-36)]
2019-08-02T16:40:43Z:     amazon-ebs: Using /etc/ansible/ansible.cfg as config file
2019-08-02T16:40:43Z:     amazon-ebs: setting up inventory plugins
2019-08-02T16:40:43Z:     amazon-ebs: host_list declined parsing /tmp/packer-provisioner-ansible761946525 as it did not pass it's verify_file() method
2019-08-02T16:40:43Z:     amazon-ebs: script declined parsing /tmp/packer-provisioner-ansible761946525 as it did not pass it's verify_file() method
2019-08-02T16:40:43Z:     amazon-ebs: auto declined parsing /tmp/packer-provisioner-ansible761946525 as it did not pass it's verify_file() method
2019-08-02T16:40:43Z:     amazon-ebs: Parsed /tmp/packer-provisioner-ansible761946525 inventory source with ini plugin
2019-08-02T16:40:43Z:     amazon-ebs: Loading callback plugin default of type stdout, v2.0 from /usr/lib/python2.7/site-packages/ansible/plugins/callback/default.pyc
2019-08-02T16:40:43Z:     amazon-ebs:
2019-08-02T16:40:43Z:     amazon-ebs: PLAYBOOK: playbook.yml *********************************************************
2019-08-02T16:40:43Z:     amazon-ebs: Positional arguments: /demo/playbook.yml
2019-08-02T16:40:43Z:     amazon-ebs: become_method: sudo
2019-08-02T16:40:43Z:     amazon-ebs: inventory: (u'/tmp/packer-provisioner-ansible761946525',)
2019-08-02T16:40:43Z:     amazon-ebs: forks: 5
2019-08-02T16:40:43Z:     amazon-ebs: tags: (u'all',)
2019-08-02T16:40:43Z:     amazon-ebs: extra_vars: (u'packer_build_name=amazon-ebs packer_builder_type=amazon-ebs -o IdentitiesOnly=yes', u'ansible_ssh_private_key_file=/tmp/ansible-key651216822')
2019-08-02T16:40:43Z:     amazon-ebs: verbosity: 4
2019-08-02T16:40:43Z:     amazon-ebs: connection: smart
2019-08-02T16:40:43Z:     amazon-ebs: timeout: 10
2019-08-02T16:40:43Z:     amazon-ebs: 1 plays in /demo/playbook.yml
2019-08-02T16:40:43Z:     amazon-ebs:
2019-08-02T16:40:43Z:     amazon-ebs: PLAY [all] *********************************************************************
2019-08-02T16:40:43Z:     amazon-ebs: META: ran handlers
2019-08-02T16:40:43Z:     amazon-ebs:
2019-08-02T16:40:43Z:     amazon-ebs: TASK [uri] *********************************************************************
2019-08-02T16:40:43Z:     amazon-ebs: task path: /demo/playbook.yml:5
2019-08-02T16:40:43Z:     amazon-ebs: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: root
2019-08-02T16:40:43Z:     amazon-ebs: <127.0.0.1> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=39171 -o 'IdentityFile="/tmp/ansible-key651216822"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="root"' -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/187e25955b 127.0.0.1 '/bin/sh -c '"'"'echo ~root && sleep 0'"'"''
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 SSH proxy: accepted connection
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 authentication attempt from 127.0.0.1:49734 to 127.0.0.1:39171 as root using none
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 authentication attempt from 127.0.0.1:49734 to 127.0.0.1:39171 as root using publickey
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 new env request: LC_ALL=en_US.UTF-8
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 new exec request: /bin/sh -c 'echo ~root && sleep 0'
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [DEBUG] Opening new ssh session
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [DEBUG] starting remote command: /bin/sh -c 'echo ~root && sleep 0'
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:40:43 [INFO] 6 bytes written for 'stdout'
2019/08/02 16:40:43 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:40:43 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:40:43 [INFO] RPC endpoint: Communicator ended with: 0
2019/08/02 16:40:43 [INFO] 0 bytes written for 'stdin'
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [INFO] 6 bytes written for 'stdout'
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [INFO] 0 bytes written for 'stderr'
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [INFO] RPC client: Communicator ended with: 0
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [INFO] 0 bytes written for 'stdin'
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 new env request: LC_ALL=en_US.UTF-8
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `" && echo ansible-tmp-1564764043.63-21905139479629="` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `" ) && sleep 0'
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [DEBUG] Opening new ssh session
2019/08/02 16:40:43 packer: 2019/08/02 16:40:43 [DEBUG] starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `" && echo ansible-tmp-1564764043.63-21905139479629="` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `" ) && sleep 0'
2019/08/02 16:40:44 packer: 2019/08/02 16:40:44 [ERROR] Remote command exited with '1': /bin/sh -c '( umask 77 && mkdir -p "` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `" && echo ansible-tmp-1564764043.63-21905139479629="` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `" ) && sleep 0'
2019/08/02 16:40:44 packer: 2019/08/02 16:40:44 [INFO] RPC endpoint: Communicator ended with: 1
2019/08/02 16:40:44 [INFO] 0 bytes written for 'stdout'
2019/08/02 16:40:44 [INFO] 62 bytes written for 'stderr'
2019/08/02 16:40:44 [INFO] RPC client: Communicator ended with: 1
2019/08/02 16:40:44 [INFO] RPC endpoint: Communicator ended with: 1
2019/08/02 16:40:44 [INFO] 0 bytes written for 'stdin'
2019/08/02 16:40:44 packer: 2019/08/02 16:40:44 [INFO] 0 bytes written for 'stdout'
2019/08/02 16:40:44 packer: 2019/08/02 16:40:44 [INFO] 62 bytes written for 'stderr'
2019/08/02 16:40:44 packer: 2019/08/02 16:40:44 [INFO] RPC client: Communicator ended with: 1
2019/08/02 16:40:44 packer: 2019/08/02 16:40:44 [INFO] 0 bytes written for 'stdin'
2019-08-02T16:40:43Z:     amazon-ebs: <127.0.0.1> (0, '/root\n', 'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug1: Control socket "/root/.ansible/cp/187e25955b" does not exist\r\ndebug2: resolving "127.0.0.1" port 39171\r\ndebug2: ssh_connect_direct: needpriv 0\r\ndebug1: Connecting to 127.0.0.1 [127.0.0.1] port 39171.\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug1: fd 3 clearing O_NONBLOCK\r\ndebug1: Connection established.\r\ndebug3: timeout: 10000 ms remain after connect\r\ndebug1: permanently_set_uid: 0/0\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /tmp/ansible-key651216822 type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /tmp/ansible-key651216822-cert type -1\r\ndebug1: Enabling compatibility mode for protocol 2.0\r\ndebug1: Local version string SSH-2.0-OpenSSH_7.4\r\ndebug1: Remote protocol version 2.0, remote software version Go\r\ndebug1: no match: Go\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug1: Authenticating to 127.0.0.1:39171 as \'root\'\r\ndebug3: put_host_port: [127.0.0.1]:39171\r\ndebug3: hostkeys_foreach: reading file "/root/.ssh/known_hosts"\r\ndebug3: send packet: type 20\r\ndebug1: SSH2_MSG_KEXINIT sent\r\ndebug3: receive packet: type 20\r\ndebug1: SSH2_MSG_KEXINIT received\r\ndebug2: local client KEXINIT proposal\r\ndebug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1,ext-info-c\r\ndebug2: host key algorithms: ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ssh-ed25519-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,rsa-sha2-512,rsa-sha2-256,ssh-rsa,ssh-dss\r\ndebug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc\r\ndebug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc\r\ndebug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1\r\ndebug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1\r\ndebug2: compression ctos: zlib@openssh.com,zlib,none\r\ndebug2: compression stoc: zlib@openssh.com,zlib,none\r\ndebug2: languages ctos: \r\ndebug2: languages stoc: \r\ndebug2: first_kex_follows 0 \r\ndebug2: reserved 0 \r\ndebug2: peer server KEXINIT proposal\r\ndebug2: KEX algorithms: curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1\r\ndebug2: host key algorithms: ssh-rsa\r\ndebug2: ciphers ctos: aes128-gcm@openssh.com,chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr\r\ndebug2: ciphers stoc: aes128-gcm@openssh.com,chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr\r\ndebug2: MACs ctos: hmac-sha2-256-etm@openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha1-96\r\ndebug2: MACs stoc: hmac-sha2-256-etm@openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha1-96\r\ndebug2: compression ctos: none\r\ndebug2: compression stoc: none\r\ndebug2: languages ctos: \r\ndebug2: languages stoc: \r\ndebug2: first_kex_follows 0 \r\ndebug2: reserved 0 \r\ndebug1: kex: algorithm: curve25519-sha256@libssh.org\r\ndebug1: kex: host key algorithm: ssh-rsa\r\ndebug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: none\r\ndebug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: none\r\ndebug1: kex: curve25519-sha256@libssh.org need=64 dh_need=64\r\ndebug1: kex: curve25519-sha256@libssh.org need=64 dh_need=64\r\ndebug3: send packet: type 30\r\ndebug1: expecting SSH2_MSG_KEX_ECDH_REPLY\r\ndebug3: receive packet: type 31\r\ndebug1: Server host key: ssh-rsa SHA256:9Hk7BmQahEbOTBjCUIwfVTQzocz/5zG+vhW1ykRSf1A\r\ndebug3: put_host_port: [127.0.0.1]:39171\r\ndebug3: put_host_port: [127.0.0.1]:39171\r\ndebug3: hostkeys_foreach: reading file "/root/.ssh/known_hosts"\r\ndebug1: checking without port identifier\r\ndebug3: hostkeys_foreach: reading file "/root/.ssh/known_hosts"\r\nWarning: Permanently added \'[127.0.0.1]:39171\' (RSA) to the list of known hosts.\r\ndebug3: send packet: type 21\r\ndebug2: set_newkeys: mode 1\r\ndebug1: rekey after 134217728 blocks\r\ndebug1: SSH2_MSG_NEWKEYS sent\r\ndebug1: expecting SSH2_MSG_NEWKEYS\r\ndebug3: receive packet: type 21\r\ndebug1: SSH2_MSG_NEWKEYS received\r\ndebug2: set_newkeys: mode 0\r\ndebug1: rekey after 134217728 blocks\r\ndebug2: key: /tmp/ansible-key651216822 ((nil)), explicit\r\ndebug3: send packet: type 5\r\ndebug3: receive packet: type 6\r\ndebug2: service_accept: ssh-userauth\r\ndebug1: SSH2_MSG_SERVICE_ACCEPT received\r\ndebug3: send packet: type 50\r\ndebug3: receive packet: type 51\r\ndebug1: Authentications that can continue: publickey\r\ndebug3: start over, passed a different list publickey\r\ndebug3: preferred gssapi-with-mic,gssapi-keyex,hostbased,publickey\r\ndebug3: authmethod_lookup publickey\r\ndebug3: remaining preferred: ,gssapi-keyex,hostbased,publickey\r\ndebug3: authmethod_is_enabled publickey\r\ndebug1: Next authentication method: publickey\r\ndebug1: Trying private key: /tmp/ansible-key651216822\r\ndebug3: sign_and_send_pubkey: RSA SHA256:+EvQb7L6AuvtD0FV02vhxJMHkN7bJKIpdEIta7BIjCc\r\ndebug3: send packet: type 50\r\ndebug2: we sent a publickey packet, wait for reply\r\ndebug3: receive packet: type 52\r\ndebug1: Authentication succeeded (publickey).\r\nAuthenticated to 127.0.0.1 ([127.0.0.1]:39171).\r\ndebug1: setting up multiplex master socket\r\ndebug3: muxserver_listen: temporary control path /root/.ansible/cp/187e25955b.Skt6I6LG43HU0Abl\r\ndebug2: fd 4 setting O_NONBLOCK\r\ndebug3: fd 4 is O_NONBLOCK\r\ndebug3: fd 4 is O_NONBLOCK\r\ndebug1: channel 0: new [/root/.ansible/cp/187e25955b]\r\ndebug3: muxserver_listen: mux listener channel 0 fd 4\r\ndebug2: fd 3 setting TCP_NODELAY\r\ndebug3: ssh_packet_set_tos: set IP_TOS 0x08\r\ndebug1: control_persist_detach: backgrounding master process\r\ndebug2: control_persist_detach: background process is 14947\r\ndebug2: fd 4 setting O_NONBLOCK\r\ndebug1: forking to background\r\ndebug1: Entering interactive session.\r\ndebug1: pledge: id\r\ndebug2: set_control_persist_exit_time: schedule exit in 60 seconds\r\ndebug1: multiplexing control connection\r\ndebug2: fd 5 setting O_NONBLOCK\r\ndebug3: fd 5 is O_NONBLOCK\r\ndebug1: channel 1: new [mux-control]\r\ndebug3: channel_post_mux_listener: new mux channel 1 fd 5\r\ndebug3: mux_master_read_cb: channel 1: hello sent\r\ndebug2: set_control_persist_exit_time: cancel scheduled exit\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x00000001 len 4\r\ndebug2: process_mux_master_hello: channel 1 slave version 4\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x10000004 len 4\r\ndebug2: process_mux_alive_check: channel 1: alive check\r\ndebug3: mux_client_request_alive: done pid = 14949\r\ndebug3: mux_client_request_session: session request sent\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x10000002 len 97\r\ndebug2: process_mux_new_session: channel 1: request tty 0, X 0, agent 0, subsys 0, term "xterm", cmd "/bin/sh -c \'echo ~root && sleep 0\'", env 1\r\ndebug3: process_mux_new_session: got fds stdin 6, stdout 7, stderr 8\r\ndebug2: fd 7 setting O_NONBLOCK\r\ndebug2: fd 8 setting O_NONBLOCK\r\ndebug1: channel 2: new [client-session]\r\ndebug2: process_mux_new_session: channel_new: 2 linked to control channel 1\r\ndebug2: channel 2: send open\r\ndebug3: send packet: type 90\r\ndebug3: receive packet: type 91\r\ndebug2: callback start\r\ndebug2: client_session2_setup: id 2\r\ndebug1: Sending environment.\r\ndebug1: Sending env LC_ALL = en_US.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending command: /bin/sh -c \'echo ~root && sleep 0\'\r\ndebug2: channel 2: request exec confirm 1\r\ndebug3: send packet: type 98\r\ndebug3: mux_session_confirm: sending success reply\r\ndebug2: callback done\r\ndebug2: channel 2: open confirm rwindow 2097152 rmax 32768\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: receive packet: type 99\r\ndebug2: channel_input_status_confirm: type 99 id 2\r\ndebug2: exec request accepted on channel 2\r\ndebug3: receive packet: type 98\r\ndebug1: client_input_channel_req: channel 2 rtype exit-status reply 0\r\ndebug3: mux_exit_message: channel 2: exit message, exitval 0\r\ndebug3: receive packet: type 97\r\ndebug2: channel 2: rcvd close\r\ndebug2: channel 2: output open -> drain\r\ndebug2: channel 2: close_read\r\ndebug2: channel 2: input open -> closed\r\ndebug3: channel 2: will not send data after close\r\ndebug2: channel 2: obuf empty\r\ndebug2: channel 2: close_write\r\ndebug2: channel 2: output drain -> closed\r\ndebug2: channel 2: send close\r\ndebug3: send packet: type 97\r\ndebug2: channel 2: is dead\r\ndebug2: channel 2: gc: notify user\r\ndebug3: mux_master_session_cleanup_cb: entering for channel 2\r\ndebug2: channel 1: rcvd close\r\ndebug2: channel 1: output open -> drain\r\ndebug2: channel 1: close_read\r\ndebug2: channel 1: input open -> closed\r\ndebug2: channel 2: gc: user detached\r\ndebug2: channel 2: is dead\r\ndebug2: channel 2: garbage collecting\r\ndebug1: channel 2: free: client-session, nchannels 3\r\ndebug3: channel 2: status: The following connections are open:\r\n  #1 mux-control (t16 r-1 i3/0 o1/0 fd 5/5 cc -1)\r\n  #2 client-session (t4 r0 i3/0 o3/0 fd -1/-1 cc -1)\r\n\r\ndebug2: channel 1: obuf empty\r\ndebug2: channel 1: close_write\r\ndebug2: channel 1: output drain -> closed\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\ndebug2: channel 1: is dead (local)\r\ndebug2: channel 1: gc: notify user\r\ndebug3: mux_master_control_cleanup_cb: entering for channel 1\r\ndebug2: channel 1: gc: user detached\r\ndebug2: channel 1: is dead (local)\r\n')
2019-08-02T16:40:43Z:     amazon-ebs: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: root
2019-08-02T16:40:43Z:     amazon-ebs: <127.0.0.1> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=39171 -o 'IdentityFile="/tmp/ansible-key651216822"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="root"' -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/187e25955b 127.0.0.1 '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `" && echo ansible-tmp-1564764043.63-21905139479629="` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `" ) && sleep 0'"'"''
2019-08-02T16:40:44Z:     amazon-ebs: <127.0.0.1> (1, '', 'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 14949\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\nmkdir: cannot create directory \xe2\x80\x98/root\xe2\x80\x99: Permission denied\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 1\r\n')
2019-08-02T16:40:44Z:     amazon-ebs: <127.0.0.1> Failed to connect to the host via ssh: OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017
2019-08-02T16:40:44Z:     amazon-ebs: debug1: Reading configuration data /etc/ssh/ssh_config
2019-08-02T16:40:44Z:     amazon-ebs: debug1: /etc/ssh/ssh_config line 58: Applying options for *
2019-08-02T16:40:44Z:     amazon-ebs: debug1: auto-mux: Trying existing master
2019-08-02T16:40:44Z:     amazon-ebs: debug2: fd 3 setting O_NONBLOCK
2019-08-02T16:40:44Z:     amazon-ebs: debug2: mux_client_hello_exchange: master version 4
2019-08-02T16:40:44Z:     amazon-ebs: debug3: mux_client_forwards: request forwardings: 0 local, 0 remote
2019-08-02T16:40:44Z:     amazon-ebs: debug3: mux_client_request_session: entering
2019-08-02T16:40:44Z:     amazon-ebs: debug3: mux_client_request_alive: entering
2019-08-02T16:40:44Z:     amazon-ebs: debug3: mux_client_request_alive: done pid = 14949
2019-08-02T16:40:44Z:     amazon-ebs: debug3: mux_client_request_session: session request sent
2019-08-02T16:40:44Z:     amazon-ebs: debug1: mux_client_request_session: master session id: 2
2019-08-02T16:40:44Z:     amazon-ebs: mkdir: cannot create directory ‘/root’: Permission denied
2019-08-02T16:40:44Z:     amazon-ebs: debug3: mux_client_read_packet: read header failed: Broken pipe
2019-08-02T16:40:44Z:     amazon-ebs: debug2: Received exit status from master 1
2019-08-02T16:40:44Z:     amazon-ebs: fatal: [default]: UNREACHABLE! => {
2019-08-02T16:40:44Z:     amazon-ebs:     "changed": false,
2019-08-02T16:40:44Z:     amazon-ebs:     "msg": "Authentication or permission failure. In some cases, you may have been able to authenticate and did not have permissions on the target directory. Consider changing the remote tmp path in ansible.cfg to a path rooted in \"/tmp\". Failed command was: ( umask 77 && mkdir -p \"` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `\" && echo ansible-tmp-1564764043.63-21905139479629=\"` echo /root/.ansible/tmp/ansible-tmp-1564764043.63-21905139479629 `\" ), exited with result 1, stderr output: OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 58: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 14949\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\nmkdir: cannot create directory ‘/root’: Permission denied\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 1\r\n",
2019-08-02T16:40:44Z:     amazon-ebs:     "unreachable": true
2019-08-02T16:40:44Z:     amazon-ebs: }
2019-08-02T16:40:44Z:     amazon-ebs:
2019-08-02T16:40:44Z:     amazon-ebs: PLAY RECAP *********************************************************************
2019-08-02T16:40:44Z:     amazon-ebs: default                    : ok=0    changed=0    unreachable=1    failed=0    skipped=0    rescued=0    ignored=0
2019-08-02T16:40:44Z:     amazon-ebs:
2019/08/02 16:40:44 packer: 2019/08/02 16:40:44 shutting down the SSH proxy
2019/08/02 16:40:44 [INFO] (telemetry) ending ansible
2019-08-02T16:40:44Z: ==> amazon-ebs: Terminating the source AWS instance...
2019-08-02T16:42:00Z: ==> amazon-ebs: Cleaning up any extra volumes...
2019-08-02T16:42:01Z: ==> amazon-ebs: Destroying volume (vol-0fb1c5639e4b2abd6)...
2019-08-02T16:42:01Z: ==> amazon-ebs: Deleting temporary security group...
2019-08-02T16:42:01Z: ==> amazon-ebs: Deleting temporary keypair...
2019/08/02 16:42:01 [INFO] (telemetry) ending amazon-ebs
2019/08/02 16:42:01 ui error: 2019-08-02T16:42:01Z: Build 'amazon-ebs' errored: Error executing Ansible: Non-zero exit status: exit status 4
2019/08/02 16:42:01 machine readable: error-count []string{"1"}
2019/08/02 16:42:01 ui error:
==> Some builds didn't complete successfully and had errors:
2019/08/02 16:42:01 machine readable: amazon-ebs,error []string{"Error executing Ansible: Non-zero exit status: exit status 4"}
2019/08/02 16:42:01 ui error: --> amazon-ebs: Error executing Ansible: Non-zero exit status: exit status 4
==> Builds finished but no artifacts were created.
2019/08/02 16:42:01 [INFO] (telemetry) Finalizing.
2019-08-02T16:42:01Z: Build 'amazon-ebs' errored: Error executing Ansible: Non-zero exit status: exit status 4

==> Some builds didn't complete successfully and had errors:
--> amazon-ebs: Error executing Ansible: Non-zero exit status: exit status 4

==> Builds finished but no artifacts were created.
2019/08/02 16:42:02 waiting for all plugin processes to complete...
2019/08/02 16:42:02 /packer: plugin process exited
2019/08/02 16:42:02 /packer: plugin process exited