Skip to content

Instantly share code, notes, and snippets.

@Helcaraxan
Last active November 27, 2019 18:44
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save Helcaraxan/d9aa69b0c3bd4a60d74be35aba91e142 to your computer and use it in GitHub Desktop.
Save Helcaraxan/d9aa69b0c3bd4a60d74be35aba91e142 to your computer and use it in GitHub Desktop.
Packer / Ansible username collision logs
ansible@packer-ansible-debug-controller:~/ansible-test$ PACKER_LOG=1 PROJECT_ID=<redacted> ./run.sh
Creating target GCE instance in project '<redacted>' in zone 'europe-west1-b'.
Packer will create and connect via a default non-root 'packer' user on the instance.
Ansible will create a non-default 'ansible' user.
2019/11/27 18:36:51 [INFO] Packer version: 1.4.5
2019/11/27 18:36:51 Packer Target OS/Arch: linux amd64
2019/11/27 18:36:51 Built with Go Version: go1.13.4
2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 Using internal plugin for digitalocean
2019/11/27 18:36:51 Using internal plugin for docker
2019/11/27 18:36:51 Using internal plugin for hyperv-vmcx
2019/11/27 18:36:51 Using internal plugin for parallels-pvm
2019/11/27 18:36:51 Using internal plugin for qemu
2019/11/27 18:36:51 Using internal plugin for virtualbox-iso
2019/11/27 18:36:51 Using internal plugin for cloudstack
2019/11/27 18:36:51 Using internal plugin for oneandone
2019/11/27 18:36:51 Using internal plugin for virtualbox-ovf
2019/11/27 18:36:51 Using internal plugin for vmware-vmx
2019/11/27 18:36:51 Using internal plugin for googlecompute
2019/11/27 18:36:51 Using internal plugin for hyperone
2019/11/27 18:36:51 Using internal plugin for jdcloud
2019/11/27 18:36:51 Using internal plugin for openstack
2019/11/27 18:36:51 Using internal plugin for oracle-classic
2019/11/27 18:36:51 Using internal plugin for osc-chroot
2019/11/27 18:36:51 Using internal plugin for scaleway
2019/11/27 18:36:51 Using internal plugin for vmware-iso
2019/11/27 18:36:51 Using internal plugin for azure-chroot
2019/11/27 18:36:51 Using internal plugin for lxc
2019/11/27 18:36:51 Using internal plugin for osc-bsusurrogate
2019/11/27 18:36:51 Using internal plugin for amazon-ebs
2019/11/27 18:36:51 Using internal plugin for amazon-ebsvolume
2019/11/27 18:36:51 Using internal plugin for file
2019/11/27 18:36:51 Using internal plugin for null
2019/11/27 18:36:51 Using internal plugin for profitbricks
2019/11/27 18:36:51 Using internal plugin for amazon-ebssurrogate
2019/11/27 18:36:51 Using internal plugin for lxd
2019/11/27 18:36:51 Using internal plugin for osc-bsuvolume
2019/11/27 18:36:51 Using internal plugin for parallels-iso
2019/11/27 18:36:51 Using internal plugin for proxmox
2019/11/27 18:36:51 Using internal plugin for vagrant
2019/11/27 18:36:51 Using internal plugin for hcloud
2019/11/27 18:36:51 Using internal plugin for amazon-chroot
2019/11/27 18:36:51 Using internal plugin for amazon-instance
2019/11/27 18:36:51 Using internal plugin for ncloud
2019/11/27 18:36:51 Using internal plugin for osc-bsu
2019/11/27 18:36:51 Using internal plugin for tencentcloud-cvm
2019/11/27 18:36:51 Using internal plugin for triton
2019/11/27 18:36:51 Using internal plugin for ucloud-uhost
2019/11/27 18:36:51 Using internal plugin for alicloud-ecs
2019/11/27 18:36:51 Using internal plugin for yandex
2019/11/27 18:36:51 Using internal plugin for virtualbox-vm
2019/11/27 18:36:51 Using internal plugin for hyperv-iso
2019/11/27 18:36:51 Using internal plugin for linode
2019/11/27 18:36:51 Using internal plugin for oracle-oci
2019/11/27 18:36:51 Using internal plugin for azure-arm
2019/11/27 18:36:51 Using internal plugin for inspec
2019/11/27 18:36:51 Using internal plugin for powershell
2019/11/27 18:36:51 Using internal plugin for puppet-server
2019/11/27 18:36:51 Using internal plugin for salt-masterless
2019/11/27 18:36:51 Using internal plugin for windows-restart
2019/11/27 18:36:51 Using internal plugin for ansible
2019/11/27 18:36:51 Using internal plugin for breakpoint
2019/11/27 18:36:51 Using internal plugin for file
2019/11/27 18:36:51 Using internal plugin for sleep
2019/11/27 18:36:51 Using internal plugin for chef-client
2019/11/27 18:36:51 Using internal plugin for chef-solo
2019/11/27 18:36:51 Using internal plugin for converge
2019/11/27 18:36:51 Using internal plugin for puppet-masterless
2019/11/27 18:36:51 Using internal plugin for windows-shell
2019/11/27 18:36:51 Using internal plugin for ansible-local
2019/11/27 18:36:51 Using internal plugin for shell
2019/11/27 18:36:51 Using internal plugin for shell-local
2019/11/27 18:36:51 Using internal plugin for docker-import
2019/11/27 18:36:51 Using internal plugin for docker-push
2019/11/27 18:36:51 Using internal plugin for googlecompute-export
2019/11/27 18:36:51 Using internal plugin for manifest
2019/11/27 18:36:51 Using internal plugin for vagrant
2019/11/27 18:36:51 Using internal plugin for alicloud-import
2019/11/27 18:36:51 Using internal plugin for amazon-import
2019/11/27 18:36:51 Using internal plugin for artifice
2019/11/27 18:36:51 Using internal plugin for compress
2019/11/27 18:36:51 Using internal plugin for digitalocean-import
2019/11/27 18:36:51 Using internal plugin for vagrant-cloud
2019/11/27 18:36:51 Using internal plugin for docker-save
2019/11/27 18:36:51 Using internal plugin for ucloud-import
2019/11/27 18:36:51 Using internal plugin for vsphere
2019/11/27 18:36:51 Using internal plugin for vsphere-template
2019/11/27 18:36:51 Using internal plugin for checksum
2019/11/27 18:36:51 Using internal plugin for docker-tag
2019/11/27 18:36:51 Using internal plugin for exoscale-import
2019/11/27 18:36:51 Using internal plugin for googlecompute-import
2019/11/27 18:36:51 Using internal plugin for shell-local
2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 Attempting to open config file: /home/ansible/.packerconfig
2019/11/27 18:36:51 [WARN] Config file doesn't exist: /home/ansible/.packerconfig
2019/11/27 18:36:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[alicloud-ecs:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-alicloud-ecs amazon-chroot:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-chroot amazon-ebs:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebs amazon-ebssurrogate:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebssurrogate amazon-ebsvolume:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebsvolume amazon-instance:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-instance azure-arm:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-arm azure-chroot:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-chroot cloudstack:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-cloudstack digitalocean:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-digitalocean docker:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-docker file:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-file googlecompute:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-googlecompute hcloud:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hcloud hyperone:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperone hyperv-iso:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-iso hyperv-vmcx:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-vmcx jdcloud:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-jdcloud linode:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-linode lxc:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxc lxd:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxd ncloud:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-ncloud null:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-null oneandone:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oneandone openstack:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-openstack oracle-classic:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-classic oracle-oci:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-oci osc-bsu:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-osc-bsu osc-bsusurrogate:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-osc-bsusurrogate osc-bsuvolume:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-osc-bsuvolume osc-chroot:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-osc-chroot parallels-iso:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-iso parallels-pvm:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-pvm profitbricks:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-profitbricks proxmox:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-proxmox qemu:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-qemu scaleway:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-scaleway tencentcloud-cvm:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-tencentcloud-cvm triton:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-triton ucloud-uhost:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-ucloud-uhost vagrant:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vagrant virtualbox-iso:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-iso virtualbox-ovf:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-ovf virtualbox-vm:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-vm vmware-iso:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-iso vmware-vmx:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-vmx yandex:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-yandex] PostProcessors:map[alicloud-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-alicloud-import amazon-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-amazon-import artifice:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-artifice checksum:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-checksum compress:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-compress digitalocean-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-digitalocean-import docker-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-import docker-push:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-push docker-save:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-save docker-tag:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-tag exoscale-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-exoscale-import googlecompute-export:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-export googlecompute-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-import manifest:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-manifest shell-local:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-shell-local ucloud-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-ucloud-import vagrant:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant vagrant-cloud:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant-cloud vsphere:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere vsphere-template:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere-template] Provisioners:map[ansible:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible ansible-local:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible-local breakpoint:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-breakpoint chef-client:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-client chef-solo:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-solo converge:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-converge file:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-file inspec:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-inspec powershell:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-powershell puppet-masterless:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-masterless puppet-server:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-server salt-masterless:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-salt-masterless shell:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell shell-local:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell-local sleep:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-sleep windows-restart:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-restart windows-shell:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-shell]}
2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 Setting cache directory: /home/ansible/ansible-test/packer_cache
2019/11/27 18:36:51 Loading builder: googlecompute
2019/11/27 18:36:51 Plugin could not be found. Checking same directory as executable.
2019/11/27 18:36:51 Current exe path: /home/ansible/go/bin/packer
2019/11/27 18:36:51 Creating plugin client for path: /home/ansible/go/bin/packer
2019/11/27 18:36:51 Starting plugin: /home/ansible/go/bin/packer []string{"/home/ansible/go/bin/packer", "plugin", "packer-builder-googlecompute"}
2019/11/27 18:36:51 Waiting for RPC address for: /home/ansible/go/bin/packer
2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 [INFO] Packer version: 1.4.5
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Packer Target OS/Arch: linux amd64
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Built with Go Version: go1.13.4
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Attempting to open config file: /home/ansible/.packerconfig
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 [WARN] Config file doesn't exist: /home/ansible/.packerconfig
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Setting cache directory: /home/ansible/ansible-test/packer_cache
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 args: []string{"packer-builder-googlecompute"}
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Plugin minimum port: 10000
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Plugin maximum port: 25000
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Plugin address: unix /tmp/packer-plugin113130101
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Waiting for connection...
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Serving a plugin connection...
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 Loading provisioner: ansible
2019/11/27 18:36:51 Plugin could not be found. Checking same directory as executable.
2019/11/27 18:36:51 Current exe path: /home/ansible/go/bin/packer
2019/11/27 18:36:51 Creating plugin client for path: /home/ansible/go/bin/packer
2019/11/27 18:36:51 Starting plugin: /home/ansible/go/bin/packer []string{"/home/ansible/go/bin/packer", "plugin", "packer-provisioner-ansible"}
2019/11/27 18:36:51 Waiting for RPC address for: /home/ansible/go/bin/packer
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 [INFO] Packer version: 1.4.5
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Packer Target OS/Arch: linux amd64
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Built with Go Version: go1.13.4
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Attempting to open config file: /home/ansible/.packerconfig
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 [WARN] Config file doesn't exist: /home/ansible/.packerconfig
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Setting cache directory: /home/ansible/ansible-test/packer_cache
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 args: []string{"packer-provisioner-ansible"}
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Plugin minimum port: 10000
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Plugin maximum port: 25000
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Plugin address: unix /tmp/packer-plugin950146200
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Waiting for connection...
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Serving a plugin connection...
2019/11/27 18:36:51 packer: 2019/11/27 18:36:51 Detected home directory from env var: /home/ansible
googlecompute output will be in this color.
2019/11/27 18:36:51 Build debug mode: false
2019/11/27 18:36:51 Force build: false
2019/11/27 18:36:51 On error:
2019/11/27 18:36:51 Preparing build: googlecompute
2019/11/27 18:36:52 Waiting on builds to complete...
2019/11/27 18:36:52 Starting build run: googlecompute
2019/11/27 18:36:52 Running builder: googlecompute
2019/11/27 18:36:52 [INFO] (telemetry) Starting builder googlecompute
2019/11/27 18:36:52 packer: 2019/11/27 18:36:52 ansible-playbook version: 2.9.1
2019/11/27 18:36:52 packer: 2019/11/27 18:36:52 [INFO] Requesting Google token via GCE API Default Client Token Source...
2019/11/27 18:36:52 packer: 2019/11/27 18:36:52 [INFO] Instantiating GCE client...
2019-11-27T18:36:52Z: ==> googlecompute: Checking image does not exist...
2019-11-27T18:36:53Z: ==> googlecompute: Creating temporary SSH key for instance...
2019-11-27T18:37:01Z: ==> googlecompute: Using image: ubuntu-1804-bionic-v20191113
2019-11-27T18:37:01Z: ==> googlecompute: Creating instance...
2019-11-27T18:37:01Z: googlecompute: Loading zone: europe-west1-b
2019-11-27T18:37:01Z: googlecompute: Loading machine type: n1-standard-1
2019-11-27T18:37:02Z: googlecompute: Requesting instance creation...
2019-11-27T18:37:04Z: googlecompute: Waiting for creation operation to complete...
2019/11/27 18:37:04 packer: 2019/11/27 18:37:04 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:37:06 packer: 2019/11/27 18:37:06 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:37:08 packer: 2019/11/27 18:37:08 Retryable error: retrying for state DONE, got RUNNING
2019-11-27T18:37:11Z: googlecompute: Instance has been created!
2019-11-27T18:37:11Z: ==> googlecompute: Waiting for the instance to become running...
2019-11-27T18:37:11Z: googlecompute: IP: 34.77.229.102
2019-11-27T18:37:11Z: ==> googlecompute: Using ssh communicator to connect: 34.77.229.102
2019/11/27 18:37:11 packer: 2019/11/27 18:37:11 [INFO] Waiting for SSH, up to timeout: 5m0s
2019-11-27T18:37:11Z: ==> googlecompute: Waiting for SSH to become available...
2019/11/27 18:37:26 packer: 2019/11/27 18:37:26 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 34.77.229.102:22: i/o timeout
2019/11/27 18:37:34 packer: 2019/11/27 18:37:34 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 34.77.229.102:22: connect: connection refused
2019/11/27 18:37:39 packer: 2019/11/27 18:37:39 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 34.77.229.102:22: connect: connection refused
2019/11/27 18:37:44 packer: 2019/11/27 18:37:44 [INFO] Attempting SSH connection to 34.77.229.102:22...
2019/11/27 18:37:44 packer: 2019/11/27 18:37:44 [DEBUG] Config to &ssh.Config{SSHConfig:(*ssh.ClientConfig)(0xc0001ff790), Connection:(func() (net.Conn, error))(0x1202d80), Pty:false, DisableAgentForwarding:false, HandshakeTimeout:0, UseSftp:false, KeepAliveInterval:5000000000, Timeout:0, Tunnels:[]ssh.TunnelSpec(nil)}...
2019/11/27 18:37:44 packer: 2019/11/27 18:37:44 [DEBUG] reconnecting to TCP connection for SSH
2019/11/27 18:37:44 packer: 2019/11/27 18:37:44 [DEBUG] handshaking with SSH
2019/11/27 18:37:44 packer: 2019/11/27 18:37:44 [DEBUG] handshake complete!
2019/11/27 18:37:44 packer: 2019/11/27 18:37:44 [INFO] no local agent socket, will not connect agent
2019-11-27T18:37:44Z: ==> googlecompute: Connected to SSH!
2019/11/27 18:37:44 packer: 2019/11/27 18:37:44 Running the provision hook
2019/11/27 18:37:44 [INFO] (telemetry) Starting provisioner ansible
2019-11-27T18:37:44Z: ==> googlecompute: Provisioning with Ansible...
2019-11-27T18:37:45Z: ==> googlecompute: Executing Ansible: ansible-playbook --extra-vars packer_build_name=googlecompute packer_builder_type=googlecompute -o IdentitiesOnly=yes -i /tmp/packer-provisioner-ansible318136714 /home/ansible/ansible-test/ansible/playbook.yaml -e ansible_ssh_private_key_file=/tmp/ansible-key015085847 -vvv --extra-vars target_user=ansible
2019/11/27 18:37:45 packer: 2019/11/27 18:37:45 SSH proxy: serving on 127.0.0.1:38375
2019-11-27T18:37:46Z: googlecompute: ansible-playbook 2.9.1
2019-11-27T18:37:46Z: googlecompute: config file = None
2019-11-27T18:37:46Z: googlecompute: configured module search path = [u'/home/ansible/.ansible/plugins/modules', u'/usr/share/ansible/plugins/modules']
2019-11-27T18:37:46Z: googlecompute: ansible python module location = /home/ansible/.local/lib/python2.7/site-packages/ansible
2019-11-27T18:37:46Z: googlecompute: executable location = /home/ansible/.local/bin/ansible-playbook
2019-11-27T18:37:46Z: googlecompute: python version = 2.7.15+ (default, Oct 7 2019, 17:39:04) [GCC 7.4.0]
2019-11-27T18:37:46Z: googlecompute: No config file found; using defaults
2019-11-27T18:37:46Z: googlecompute: host_list declined parsing /tmp/packer-provisioner-ansible318136714 as it did not pass its verify_file() method
2019-11-27T18:37:46Z: googlecompute: script declined parsing /tmp/packer-provisioner-ansible318136714 as it did not pass its verify_file() method
2019-11-27T18:37:46Z: googlecompute: auto declined parsing /tmp/packer-provisioner-ansible318136714 as it did not pass its verify_file() method
2019-11-27T18:37:46Z: googlecompute: Parsed /tmp/packer-provisioner-ansible318136714 inventory source with ini plugin
2019-11-27T18:37:47Z: googlecompute:
2019-11-27T18:37:47Z: googlecompute: PLAYBOOK: playbook.yaml ********************************************************
2019-11-27T18:37:47Z: googlecompute: 1 plays in /home/ansible/ansible-test/ansible/playbook.yaml
2019-11-27T18:37:47Z: googlecompute:
2019-11-27T18:37:47Z: googlecompute: PLAY [all] *********************************************************************
2019-11-27T18:37:47Z: googlecompute:
2019-11-27T18:37:47Z: googlecompute: TASK [Gathering Facts] *********************************************************
2019-11-27T18:37:47Z: googlecompute: task path: /home/ansible/ansible-test/ansible/playbook.yaml:2
2019-11-27T18:37:47Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:47Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'echo ~ansible && sleep 0'"'"''
2019/11/27 18:37:47 packer: 2019/11/27 18:37:47 SSH proxy: accepted connection
2019/11/27 18:37:47 packer: 2019/11/27 18:37:47 authentication attempt from 127.0.0.1:60596 to 127.0.0.1:38375 as ansible using none
2019/11/27 18:37:47 packer: 2019/11/27 18:37:47 authentication attempt from 127.0.0.1:60596 to 127.0.0.1:38375 as ansible using publickey
2019/11/27 18:37:47 packer: 2019/11/27 18:37:47 new env request: LANG=C.UTF-8
2019/11/27 18:37:47 packer: 2019/11/27 18:37:47 new exec request: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:37:47 packer: 2019/11/27 18:37:47 [DEBUG] Opening new ssh session
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [DEBUG] starting remote command: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:37:48 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:37:48 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:48 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:48 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:48 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:48Z: googlecompute: <127.0.0.1> (0, '~ansible\n', "Warning: Permanently added '[127.0.0.1]:38375' (RSA) to the list of known hosts.\r\n")
2019-11-27T18:37:48Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:48Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166 `" && echo ansible-tmp-1574879867.31-4387082799166="` echo ~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166 `" ) && sleep 0'"'"''
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 new env request: LANG=C.UTF-8
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166 `" && echo ansible-tmp-1574879867.31-4387082799166="` echo ~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166 `" ) && sleep 0'
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [DEBUG] Opening new ssh session
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [DEBUG] starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166 `" && echo ansible-tmp-1574879867.31-4387082799166="` echo ~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166 `" ) && sleep 0'
2019/11/27 18:37:48 [INFO] 99 bytes written for 'stdout'
2019/11/27 18:37:48 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:48 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:48 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:48 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] 99 bytes written for 'stdout'
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:48 packer: 2019/11/27 18:37:48 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:48Z: googlecompute: <127.0.0.1> (0, 'ansible-tmp-1574879867.31-4387082799166=~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166\n', '')
2019-11-27T18:37:49Z: googlecompute: <default> Attempting python interpreter discovery
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 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/11/27 18:37:49 packer: 2019/11/27 18:37:49 new env request: LANG=C.UTF-8
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 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/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] Opening new ssh session
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [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/11/27 18:37:49 [INFO] 66 bytes written for 'stdout'
2019/11/27 18:37:49 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:49 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:49 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:49 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 66 bytes written for 'stdout'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> (0, 'PLATFORM\nLinux\nFOUND\n/usr/bin/python3.6\n/usr/bin/python3\nENDFOUND\n', '')
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'/usr/bin/python3.6 && sleep 0'"'"''
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 new env request: LANG=C.UTF-8
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 new exec request: /bin/sh -c '/usr/bin/python3.6 && sleep 0'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 1234 bytes written for 'stdin'
2019/11/27 18:37:49 [INFO] 1234 bytes written for 'stdin'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] Opening new ssh session
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] starting remote command: /bin/sh -c '/usr/bin/python3.6 && sleep 0'
2019/11/27 18:37:49 [INFO] 495 bytes written for 'stdout'
2019/11/27 18:37:49 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:49 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:49 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 495 bytes written for 'stdout'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] RPC client: Communicator ended with: 0
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> (0, '{"platform_dist_result": ["Ubuntu", "18.04", "bionic"], "osrelease_content": "NAME=\\"Ubuntu\\"\\nVERSION=\\"18.04.3 LTS (Bionic Beaver)\\"\\nID=ubuntu\\nID_LIKE=debian\\nPRETTY_NAME=\\"Ubuntu 18.04.3 LTS\\"\\nVERSION_ID=\\"18.04\\"\\nHOME_URL=\\"https://www.ubuntu.com/\\"\\nSUPPORT_URL=\\"https://help.ubuntu.com/\\"\\nBUG_REPORT_URL=\\"https://bugs.launchpad.net/ubuntu/\\"\\nPRIVACY_POLICY_URL=\\"https://www.ubuntu.com/legal/terms-and-policies/privacy-policy\\"\\nVERSION_CODENAME=bionic\\nUBUNTU_CODENAME=bionic\\n"}\n', '')
2019-11-27T18:37:49Z: googlecompute: Using module file /home/ansible/.local/lib/python2.7/site-packages/ansible/modules/system/setup.py
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> PUT /home/ansible/.ansible/tmp/ansible-local-15815XFOVbb/tmp44DBN1 TO ~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> SSH: EXEC scp -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a /home/ansible/.ansible/tmp/ansible-local-15815XFOVbb/tmp44DBN1 '[127.0.0.1]:'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py'"'"''
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 new env request: LANG=C.UTF-8
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 new exec request: scp -t '~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] Opening new ssh session
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] Starting remote scp process: scp -vt ~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 255015 bytes written for 'uploadData'
2019/11/27 18:37:49 [INFO] 255015 bytes written for 'uploadData'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] Started SCP session, beginning transfers...
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] scp: Uploading AnsiballZ_setup.py: perms=C0600 size=255015
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] SCP session complete, closing stdin pipe.
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] Waiting for SSH session to complete.
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] scp stderr (length 38): Sink: C0600 255015 AnsiballZ_setup.py
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'chmod u+x '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/'"'"'"'"'"'"'"'"' '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py'"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 new env request: LANG=C.UTF-8
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 new exec request: /bin/sh -c 'chmod u+x '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/'"'"' '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py'"'"' && sleep 0'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] Opening new ssh session
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] starting remote command: /bin/sh -c 'chmod u+x '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/'"'"' '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py'"'"' && sleep 0'
2019/11/27 18:37:49 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:49 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:49 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:49 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:49 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:49Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a -tt 127.0.0.1 '/bin/sh -c '"'"'/usr/bin/python3 '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py'"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 ansible provisioner pty-req request
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 new env request: LANG=C.UTF-8
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 new exec request: /bin/sh -c '/usr/bin/python3 '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py'"'"' && sleep 0'
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] Opening new ssh session
2019/11/27 18:37:49 packer: 2019/11/27 18:37:49 [DEBUG] starting remote command: /bin/sh -c '/usr/bin/python3 '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/AnsiballZ_setup.py'"'"' && sleep 0'
2019/11/27 18:37:50 [INFO] 17115 bytes written for 'stdout'
2019/11/27 18:37:50 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:50 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:50 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:50 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] 17115 bytes written for 'stdout'
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:50Z: googlecompute: <127.0.0.1> (0, '\n{"ansible_facts": {"ansible_env": {"MAIL": "/var/mail/packer", "USER": "packer", "SSH_CLIENT": "35.205.33.202 53890 22", "SHLVL": "1", "HOME": "/home/packer", "LOGNAME": "packer", "_": "/bin/sh", "XDG_SESSION_ID": "1", "PATH": "/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games", "XDG_RUNTIME_DIR": "/run/user/1001", "LANG": "C.UTF-8", "SHELL": "/bin/bash", "PWD": "/home/packer", "SSH_CONNECTION": "35.205.33.202 53890 10.132.0.50 22"}, "ansible_system_capabilities_enforced": "True", "ansible_system_capabilities": [""], "ansible_user_id": "packer", "ansible_user_uid": 1001, "ansible_user_gid": 1002, "ansible_user_gecos": "", "ansible_user_dir": "/home/packer", "ansible_user_shell": "/bin/bash", "ansible_real_user_id": 1001, "ansible_effective_user_id": 1001, "ansible_real_group_id": 1002, "ansible_effective_group_id": 1002, "ansible_is_chroot": false, "ansible_system": "Linux", "ansible_kernel": "5.0.0-1025-gcp", "ansible_kernel_version": "#26~18.04.1-Ubuntu SMP Mon Nov 11 13:09:18 UTC 2019", "ansible_machine": "x86_64", "ansible_python_version": "3.6.8", "ansible_fqdn": "packer-5ddec243-11d1-1e21-0fce-7f10e418533d.c.<redacted>.internal", "ansible_hostname": "packer-5ddec243-11d1-1e21-0fce-7f10e418533d", "ansible_nodename": "packer-5ddec243-11d1-1e21-0fce-7f10e418533d", "ansible_domain": "c.<redacted>.internal", "ansible_userspace_bits": "64", "ansible_architecture": "x86_64", "ansible_userspace_architecture": "x86_64", "ansible_machine_id": "ed66339a5ced6efbab63c94d4239c6da", "ansible_ssh_host_key_dsa_public": "AAAAB3NzaC1kc3MAAACBAO49+zBXRdfppMnSfht+ex3COT2Mf6Mu2Hh+FS4v/Be9KqlGv2gVnSG5I+D/P8nzqumneo9Nw1Mvl3nUWYlFul2y7wPmYSJHe3lWnCRxU/xxbVfy70+O1dK6nFmDwBj7X7xfwhLp29I6VzTWUIvRl/CXjjAWySZASd4sOz5etgFfAAAAFQCi8njfAwMC5PmUXB5z1pmTkG4SswAAAIAZZYkUlA0H6S4hGGlq2jvau2QlAcJTHUx1NRz6k82L1pGNJ6JcxIj6Fw0BWP3wH/7gjnqtmZVdfgKDvm+Gih4PMqGYaF5XENcSEhVwAFvBXnNTEFlk+oXZC/J44e9gwXDTkdnx6x6l6Ojbqcqw70l3s1PGKlVl9sulN5hz1iEh2gAAAIBq5ojRIfBUh7CaTWG8MDs0SpIX4k+7i0jaQ7ze9Blc6J9JtL5KhgAdMhPTfxrYHKyDa8nDAElLuGit6u/S5ADgjaJz56WwSVU4LADT+UJwpJkgU3io1IJhDHb61G+VLNJruRHMyz8BnrDo/JlI4ItM50nca6RV3g/rxWim1kBuAw==", "ansible_ssh_host_key_rsa_public": "AAAAB3NzaC1yc2EAAAADAQABAAABAQCdRTUlnHpt/Lq0CmYtI+50QBGqZrl+MSeiIfHyV9XapR6+ZAVVUaPr2WhZpFSfE7RyouP4l2HR9kWjQ7sbS6jNaR2aNG1nivahLpTRWtCyllJN23qhSZr9GpiN4aC692Q8Fkzs2E5Bbd/vv1CZ8myNfHpp4jFtnS0apAGjMolW89c4eGsKCVfVMMRKh1n9/y70xryWt6zVlxZWph0Sg2j/BuPn+eLRM4+I/lXZgGY/wnSPfVa9CfVUWv/rbXw4twxfEdMI5/F1kPUSHkhg5Cy0YZyQ4gRe4MVOaZ7WZBHFZJSPipk6miSiyIksebLXas9rjHJ9MOLVsBOTcTbZHdFv", "ansible_ssh_host_key_ecdsa_public": "AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBBOY9+4E1nX002YObGUle7JsI9cCSt87y7FiU7qVhyf8uTgufzePIDjaawt0HgV/VdqdlrKJCLNHIvWq7CKeBOY=", "ansible_ssh_host_key_ed25519_public": "AAAAC3NzaC1lZDI1NTE5AAAAIDhsgy9s4NERMkZ9OpkYEaBXPBWZngFSnzdUrdoceiMY", "ansible_python": {"version": {"major": 3, "minor": 6, "micro": 8, "releaselevel": "final", "serial": 0}, "version_info": [3, 6, 8, "final", 0], "executable": "/usr/bin/python3", "has_sslcontext": true, "type": "cpython"}, "ansible_distribution": "Ubuntu", "ansible_distribution_release": "bionic", "ansible_distribution_version": "18.04", "ansible_distribution_major_version": "18", "ansible_distribution_file_path": "/etc/os-release", "ansible_distribution_file_variety": "Debian", "ansible_distribution_file_parsed": true, "ansible_os_family": "Debian", "ansible_lsb": {"id": "Ubuntu", "description": "Ubuntu 18.04.3 LTS", "release": "18.04", "codename": "bionic", "major_release": "18"}, "ansible_iscsi_iqn": "", "ansible_date_time": {"year": "2019", "month": "11", "weekday": "Wednesday", "weekday_number": "3", "weeknumber": "47", "day": "27", "hour": "18", "minute": "37", "second": "50", "epoch": "1574879870", "date": "2019-11-27", "time": "18:37:50", "iso8601_micro": "2019-11-27T18:37:50.523903Z", "iso8601": "2019-11-27T18:37:50Z", "iso8601_basic": "20191127T183750523765", "iso8601_basic_short": "20191127T183750", "tz": "UTC", "tz_offset": "+0000"}, "ansible_dns": {"nameservers": ["127.0.0.53"], "options": {"edns0": true}, "search": ["c.<redacted>.internal", "google.internal"]}, "ansible_hostnqn": "", "ansible_virtualization_role": "guest", "ansible_virtualization_type": "kvm", "ansible_local": {}, "ansible_cmdline": {"BOOT_IMAGE": "/boot/vmlinuz-5.0.0-1025-gcp", "root": "LABEL=cloudimg-rootfs", "ro": true, "scsi_mod.use_blk_mq": "Y", "console": "ttyS0"}, "ansible_proc_cmdline": {"BOOT_IMAGE": "/boot/vmlinuz-5.0.0-1025-gcp", "root": "LABEL=cloudimg-rootfs", "ro": true, "scsi_mod.use_blk_mq": "Y", "console": "ttyS0"}, "ansible_selinux": {"status": "Missing selinux Python library"}, "ansible_selinux_python_present": false, "ansible_pkg_mgr": "apt", "ansible_fibre_channel_wwn": [], "ansible_fips": false, "ansible_apparmor": {"status": "enabled"}, "ansible_interfaces": ["ens4", "lo"], "ansible_lo": {"device": "lo", "mtu": 65536, "active": true, "type": "loopback", "promisc": false, "ipv4": {"address": "127.0.0.1", "broadcast": "host", "netmask": "255.0.0.0", "network": "127.0.0.0"}, "ipv6": [{"address": "::1", "prefix": "128", "scope": "host"}], "features": {"rx_checksumming": "on [fixed]", "tx_checksumming": "on", "tx_checksum_ipv4": "off [fixed]", "tx_checksum_ip_generic": "on [fixed]", "tx_checksum_ipv6": "off [fixed]", "tx_checksum_fcoe_crc": "off [fixed]", "tx_checksum_sctp": "on [fixed]", "scatter_gather": "on", "tx_scatter_gather": "on [fixed]", "tx_scatter_gather_fraglist": "on [fixed]", "tcp_segmentation_offload": "on", "tx_tcp_segmentation": "on", "tx_tcp_ecn_segmentation": "on", "tx_tcp_mangleid_segmentation": "on", "tx_tcp6_segmentation": "on", "udp_fragmentation_offload": "off", "generic_segmentation_offload": "on", "generic_receive_offload": "on", "large_receive_offload": "off [fixed]", "rx_vlan_offload": "off [fixed]", "tx_vlan_offload": "off [fixed]", "ntuple_filters": "off [fixed]", "receive_hashing": "off [fixed]", "highdma": "on [fixed]", "rx_vlan_filter": "off [fixed]", "vlan_challenged": "on [fixed]", "tx_lockless": "on [fixed]", "netns_local": "on [fixed]", "tx_gso_robust": "off [fixed]", "tx_fcoe_segmentation": "off [fixed]", "tx_gre_segmentation": "off [fixed]", "tx_gre_csum_segmentation": "off [fixed]", "tx_ipxip4_segmentation": "off [fixed]", "tx_ipxip6_segmentation": "off [fixed]", "tx_udp_tnl_segmentation": "off [fixed]", "tx_udp_tnl_csum_segmentation": "off [fixed]", "tx_gso_partial": "off [fixed]", "tx_sctp_segmentation": "on", "tx_esp_segmentation": "off [fixed]", "tx_udp_segmentation": "off [fixed]", "fcoe_mtu": "off [fixed]", "tx_nocache_copy": "off [fixed]", "loopback": "on [fixed]", "rx_fcs": "off [fixed]", "rx_all": "off [fixed]", "tx_vlan_stag_hw_insert": "off [fixed]", "rx_vlan_stag_hw_parse": "off [fixed]", "rx_vlan_stag_filter": "off [fixed]", "l2_fwd_offload": "off [fixed]", "hw_tc_offload": "off [fixed]", "esp_hw_offload": "off [fixed]", "esp_tx_csum_hw_offload": "off [fixed]", "rx_udp_tunnel_port_offload": "off [fixed]", "tls_hw_tx_offload": "off [fixed]", "tls_hw_rx_offload": "off [fixed]", "rx_gro_hw": "off [fixed]", "tls_hw_record": "off [fixed]"}, "timestamping": ["tx_software", "rx_software", "software"], "hw_timestamp_filters": []}, "ansible_ens4": {"device": "ens4", "macaddress": "42:01:0a:84:00:32", "mtu": 1460, "active": true, "module": "virtio_net", "type": "ether", "pciid": "virtio1", "speed": -1, "promisc": false, "ipv4": {"address": "10.132.0.50", "broadcast": "global", "netmask": "255.255.255.255", "network": "10.132.0.50"}, "ipv6": [{"address": "fe80::4001:aff:fe84:32", "prefix": "64", "scope": "link"}], "features": {"rx_checksumming": "on [fixed]", "tx_checksumming": "on", "tx_checksum_ipv4": "off [fixed]", "tx_checksum_ip_generic": "on", "tx_checksum_ipv6": "off [fixed]", "tx_checksum_fcoe_crc": "off [fixed]", "tx_checksum_sctp": "off [fixed]", "scatter_gather": "on", "tx_scatter_gather": "on", "tx_scatter_gather_fraglist": "off [fixed]", "tcp_segmentation_offload": "on", "tx_tcp_segmentation": "on", "tx_tcp_ecn_segmentation": "off [fixed]", "tx_tcp_mangleid_segmentation": "off", "tx_tcp6_segmentation": "on", "udp_fragmentation_offload": "off", "generic_segmentation_offload": "on", "generic_receive_offload": "on", "large_receive_offload": "on", "rx_vlan_offload": "off [fixed]", "tx_vlan_offload": "off [fixed]", "ntuple_filters": "off [fixed]", "receive_hashing": "off [fixed]", "highdma": "on [fixed]", "rx_vlan_filter": "off [fixed]", "vlan_challenged": "off [fixed]", "tx_lockless": "off [fixed]", "netns_local": "off [fixed]", "tx_gso_robust": "on [fixed]", "tx_fcoe_segmentation": "off [fixed]", "tx_gre_segmentation": "off [fixed]", "tx_gre_csum_segmentation": "off [fixed]", "tx_ipxip4_segmentation": "off [fixed]", "tx_ipxip6_segmentation": "off [fixed]", "tx_udp_tnl_segmentation": "off [fixed]", "tx_udp_tnl_csum_segmentation": "off [fixed]", "tx_gso_partial": "off [fixed]", "tx_sctp_segmentation": "off [fixed]", "tx_esp_segmentation": "off [fixed]", "tx_udp_segmentation": "off [fixed]", "fcoe_mtu": "off [fixed]", "tx_nocache_copy": "off", "loopback": "off [fixed]", "rx_fcs": "off [fixed]", "rx_all": "off [fixed]", "tx_vlan_stag_hw_insert": "off [fixed]", "rx_vlan_stag_hw_parse": "off [fixed]", "rx_vlan_stag_filter": "off [fixed]", "l2_fwd_offload": "off [fixed]", "hw_tc_offload": "off [fixed]", "esp_hw_offload": "off [fixed]", "esp_tx_csum_hw_offload": "off [fixed]", "rx_udp_tunnel_port_offload": "off [fixed]", "tls_hw_tx_offload": "off [fixed]", "tls_hw_rx_offload": "off [fixed]", "rx_gro_hw": "off [fixed]", "tls_hw_record": "off [fixed]"}, "timestamping": ["tx_software", "rx_software", "software"], "hw_timestamp_filters": []}, "ansible_default_ipv4": {"gateway": "10.132.0.1", "interface": "ens4", "address": "10.132.0.50", "broadcast": "global", "netmask": "255.255.255.255", "network": "10.132.0.50", "macaddress": "42:01:0a:84:00:32", "mtu": 1460, "type": "ether", "alias": "ens4"}, "ansible_default_ipv6": {}, "ansible_all_ipv4_addresses": ["10.132.0.50"], "ansible_all_ipv6_addresses": ["fe80::4001:aff:fe84:32"], "ansible_processor": ["0", "GenuineIntel", "Intel(R) Xeon(R) CPU @ 2.30GHz"], "ansible_processor_count": 1, "ansible_processor_cores": 1, "ansible_processor_threads_per_core": 1, "ansible_processor_vcpus": 1, "ansible_memtotal_mb": 3690, "ansible_memfree_mb": 3075, "ansible_swaptotal_mb": 0, "ansible_swapfree_mb": 0, "ansible_memory_mb": {"real": {"total": 3690, "used": 615, "free": 3075}, "nocache": {"free": 3461, "used": 229}, "swap": {"total": 0, "free": 0, "used": 0, "cached": 0}}, "ansible_bios_date": "01/01/2011", "ansible_bios_version": "Google", "ansible_form_factor": "Other", "ansible_product_name": "Google Compute Engine", "ansible_product_serial": "NA", "ansible_product_uuid": "NA", "ansible_product_version": "NA", "ansible_system_vendor": "Google", "ansible_devices": {"loop1": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "4096", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop6": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop4": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop2": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop0": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "4096", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "182480", "sectorsize": "512", "size": "89.10 MB", "host": "", "holders": []}, "loop7": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "sda": {"virtual": 1, "links": {"ids": ["google-persistent-disk-0", "scsi-0Google_PersistentDisk_persistent-disk-0"], "uuids": [], "labels": [], "masters": []}, "vendor": "Google", "model": "PersistentDisk", "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "4096", "partitions": {"sda14": {"links": {"ids": ["google-persistent-disk-0-part14", "scsi-0Google_PersistentDisk_persistent-disk-0-part14"], "uuids": [], "labels": [], "masters": []}, "start": "2048", "sectors": "8192", "sectorsize": 512, "size": "4.00 MB", "uuid": null, "holders": []}, "sda15": {"links": {"ids": ["google-persistent-disk-0-part15", "scsi-0Google_PersistentDisk_persistent-disk-0-part15"], "uuids": ["84B5-FED0"], "labels": ["UEFI"], "masters": []}, "start": "10240", "sectors": "217088", "sectorsize": 512, "size": "106.00 MB", "uuid": "84B5-FED0", "holders": []}, "sda1": {"links": {"ids": ["google-persistent-disk-0-part1", "scsi-0Google_PersistentDisk_persistent-disk-0-part1"], "uuids": ["e0c095ca-21b2-4a02-bbea-0dc95c07cfc8"], "labels": ["cloudimg-rootfs"], "masters": []}, "start": "227328", "sectors": "20744159", "sectorsize": 512, "size": "9.89 GB", "uuid": "e0c095ca-21b2-4a02-bbea-0dc95c07cfc8", "holders": []}}, "rotational": "1", "scheduler_mode": "none", "sectors": "20971520", "sectorsize": "512", "size": "10.00 GB", "host": "Non-VGA unclassified device: Red Hat, Inc. Virtio SCSI", "holders": []}, "loop5": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop3": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}}, "ansible_device_links": {"ids": {"sda14": ["google-persistent-disk-0-part14", "scsi-0Google_PersistentDisk_persistent-disk-0-part14"], "sda15": ["google-persistent-disk-0-part15", "scsi-0Google_PersistentDisk_persistent-disk-0-part15"], "sda1": ["google-persistent-disk-0-part1", "scsi-0Google_PersistentDisk_persistent-disk-0-part1"], "sda": ["google-persistent-disk-0", "scsi-0Google_PersistentDisk_persistent-disk-0"]}, "uuids": {"sda1": ["e0c095ca-21b2-4a02-bbea-0dc95c07cfc8"], "sda15": ["84B5-FED0"]}, "labels": {"sda1": ["cloudimg-rootfs"], "sda15": ["UEFI"]}, "masters": {}}, "ansible_uptime_seconds": 29, "ansible_mounts": [{"mount": "/", "device": "/dev/sda1", "fstype": "ext4", "options": "rw,relatime", "size_total": 10222829568, "size_available": 8990433280, "block_size": 4096, "block_total": 2495808, "block_available": 2194930, "block_used": 300878, "inode_total": 1290240, "inode_available": 1222060, "inode_used": 68180, "uuid": "e0c095ca-21b2-4a02-bbea-0dc95c07cfc8"}, {"mount": "/boot/efi", "device": "/dev/sda15", "fstype": "vfat", "options": "rw,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro", "size_total": 109422592, "size_available": 105652736, "block_size": 512, "block_total": 213716, "block_available": 206353, "block_used": 7363, "inode_total": 0, "inode_available": 0, "inode_used": 0, "uuid": "84B5-FED0"}, {"mount": "/snap/core/8039", "device": "/dev/loop0", "fstype": "squashfs", "options": "ro,nodev,relatime", "size_total": 93454336, "size_available": 0, "block_size": 131072, "block_total": 713, "block_available": 0, "block_used": 713, "inode_total": 12842, "inode_available": 0, "inode_used": 12842, "uuid": "N/A"}], "ansible_service_mgr": "systemd", "gather_subset": ["all"], "module_setup": true}, "invocation": {"module_args": {"gather_timeout": 10, "gather_subset": ["all"], "filter": "*", "fact_path": "/etc/ansible/facts.d"}}}\n', 'Shared connection to 127.0.0.1 closed.\r\n')
2019-11-27T18:37:50Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:50Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'rm -f -r '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/'"'"'"'"'"'"'"'"' > /dev/null 2>&1 && sleep 0'"'"''
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 new env request: LANG=C.UTF-8
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 new exec request: /bin/sh -c 'rm -f -r '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/'"'"' > /dev/null 2>&1 && sleep 0'
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [DEBUG] Opening new ssh session
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [DEBUG] starting remote command: /bin/sh -c 'rm -f -r '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879867.31-4387082799166/'"'"' > /dev/null 2>&1 && sleep 0'
2019-11-27T18:37:50Z: googlecompute: <127.0.0.1> (0, '', '')
2019/11/27 18:37:50 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:50 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:50 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:50 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:50 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:50 packer: 2019/11/27 18:37:50 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:50Z: googlecompute: ok: [default]
2019-11-27T18:37:50Z: googlecompute: META: ran handlers
2019-11-27T18:37:51Z: googlecompute:
2019-11-27T18:37:51Z: googlecompute: TASK [Setup 'ansible' user] *******************************************************
2019-11-27T18:37:51Z: googlecompute: task path: /home/ansible/ansible-test/ansible/playbook.yaml:7
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'echo ~ansible && sleep 0'"'"''
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new env request: LANG=C.UTF-8
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new exec request: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] Opening new ssh session
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] starting remote command: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:37:51 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:51 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:51 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> (0, '~ansible\n', '')
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379 `" && echo ansible-tmp-1574879871.05-174050458706379="` echo ~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379 `" ) && sleep 0'"'"''
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new env request: LANG=C.UTF-8
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379 `" && echo ansible-tmp-1574879871.05-174050458706379="` echo ~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379 `" ) && sleep 0'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] Opening new ssh session
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379 `" && echo ansible-tmp-1574879871.05-174050458706379="` echo ~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379 `" ) && sleep 0'
2019/11/27 18:37:51 [INFO] 103 bytes written for 'stdout'
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:51 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:51 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 103 bytes written for 'stdout'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> (0, 'ansible-tmp-1574879871.05-174050458706379=~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379\n', '')
2019-11-27T18:37:51Z: googlecompute: Using module file /home/ansible/.local/lib/python2.7/site-packages/ansible/modules/system/user.py
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> PUT /home/ansible/.ansible/tmp/ansible-local-15815XFOVbb/tmpXEy3FQ TO ~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> SSH: EXEC scp -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a /home/ansible/.ansible/tmp/ansible-local-15815XFOVbb/tmpXEy3FQ '[127.0.0.1]:'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py'"'"''
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new env request: LANG=C.UTF-8
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new exec request: scp -t '~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] Opening new ssh session
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] Starting remote scp process: scp -vt ~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 127768 bytes written for 'uploadData'
2019/11/27 18:37:51 [INFO] 127768 bytes written for 'uploadData'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] Started SCP session, beginning transfers...
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] scp: Uploading AnsiballZ_user.py: perms=C0600 size=127768
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] SCP session complete, closing stdin pipe.
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] Waiting for SSH session to complete.
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] scp stderr (length 37): Sink: C0600 127768 AnsiballZ_user.py
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'chmod u+x '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/'"'"'"'"'"'"'"'"' '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py'"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new env request: LANG=C.UTF-8
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new exec request: /bin/sh -c 'chmod u+x '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/'"'"' '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py'"'"' && sleep 0'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] Opening new ssh session
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] starting remote command: /bin/sh -c 'chmod u+x '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/'"'"' '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py'"'"' && sleep 0'
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:51 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:51 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a -tt 127.0.0.1 '/bin/sh -c '"'"'sudo -H -S -n -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-kazfqudxdxtugywiculmcmborigcgbld ; /usr/bin/python3 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 ansible provisioner pty-req request
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new env request: LANG=C.UTF-8
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 new exec request: /bin/sh -c 'sudo -H -S -n -u root /bin/sh -c '"'"'echo BECOME-SUCCESS-kazfqudxdxtugywiculmcmborigcgbld ; /usr/bin/python3 '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py'"'"'"'"'"'"'"'"''"'"' && sleep 0'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] Opening new ssh session
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [DEBUG] starting remote command: /bin/sh -c 'sudo -H -S -n -u root /bin/sh -c '"'"'echo BECOME-SUCCESS-kazfqudxdxtugywiculmcmborigcgbld ; /usr/bin/python3 '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/AnsiballZ_user.py'"'"'"'"'"'"'"'"''"'"' && sleep 0'
2019-11-27T18:37:51Z: googlecompute: Escalation succeeded
2019/11/27 18:37:51 [INFO] 946 bytes written for 'stdout'
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:51 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:51 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 946 bytes written for 'stdout'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:51 packer: 2019/11/27 18:37:51 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> (0, '\n{"name": "ansible", "state": "present", "system": false, "create_home": true, "changed": true, "uid": 1002, "group": 1003, "comment": "", "home": "/home/ansible", "shell": "/bin/sh", "invocation": {"module_args": {"name": "ansible", "state": "present", "non_unique": false, "force": false, "remove": false, "create_home": true, "system": false, "move_home": false, "append": false, "ssh_key_bits": 0, "ssh_key_type": "rsa", "ssh_key_comment": "ansible-generated on packer-5ddec243-11d1-1e21-0fce-7f10e418533d", "update_password": "always", "uid": null, "group": null, "groups": null, "comment": null, "home": null, "shell": null, "password": null, "login_class": null, "hidden": null, "seuser": null, "skeleton": null, "generate_ssh_key": null, "ssh_key_file": null, "ssh_key_passphrase": null, "expires": null, "password_lock": null, "local": null, "profile": null, "authorization": null, "role": null}}}\n', 'Shared connection to 127.0.0.1 closed.\r\n')
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:51Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'rm -f -r '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/'"'"'"'"'"'"'"'"' > /dev/null 2>&1 && sleep 0'"'"''
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 new env request: LANG=C.UTF-8
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 new exec request: /bin/sh -c 'rm -f -r '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/'"'"' > /dev/null 2>&1 && sleep 0'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [DEBUG] Opening new ssh session
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [DEBUG] starting remote command: /bin/sh -c 'rm -f -r '"'"'~ansible/.ansible/tmp/ansible-tmp-1574879871.05-174050458706379/'"'"' > /dev/null 2>&1 && sleep 0'
2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:52 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:52 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:52 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:52Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:37:52Z: googlecompute: changed: [default] => {
2019-11-27T18:37:52Z: googlecompute: "changed": true,
2019-11-27T18:37:52Z: googlecompute: "comment": "",
2019-11-27T18:37:52Z: googlecompute: "create_home": true,
2019-11-27T18:37:52Z: googlecompute: "group": 1003,
2019-11-27T18:37:52Z: googlecompute: "home": "/home/ansible",
2019-11-27T18:37:52Z: googlecompute: "invocation": {
2019-11-27T18:37:52Z: googlecompute: "module_args": {
2019-11-27T18:37:52Z: googlecompute: "append": false,
2019-11-27T18:37:52Z: googlecompute: "authorization": null,
2019-11-27T18:37:52Z: googlecompute: "comment": null,
2019-11-27T18:37:52Z: googlecompute: "create_home": true,
2019-11-27T18:37:52Z: googlecompute: "expires": null,
2019-11-27T18:37:52Z: googlecompute: "force": false,
2019-11-27T18:37:52Z: googlecompute: "generate_ssh_key": null,
2019-11-27T18:37:52Z: googlecompute: "group": null,
2019-11-27T18:37:52Z: googlecompute: "groups": null,
2019-11-27T18:37:52Z: googlecompute: "hidden": null,
2019-11-27T18:37:52Z: googlecompute: "home": null,
2019-11-27T18:37:52Z: googlecompute: "local": null,
2019-11-27T18:37:52Z: googlecompute: "login_class": null,
2019-11-27T18:37:52Z: googlecompute: "move_home": false,
2019-11-27T18:37:52Z: googlecompute: "name": "ansible",
2019-11-27T18:37:52Z: googlecompute: "non_unique": false,
2019-11-27T18:37:52Z: googlecompute: "password": null,
2019-11-27T18:37:52Z: googlecompute: "password_lock": null,
2019-11-27T18:37:52Z: googlecompute: "profile": null,
2019-11-27T18:37:52Z: googlecompute: "remove": false,
2019-11-27T18:37:52Z: googlecompute: "role": null,
2019-11-27T18:37:52Z: googlecompute: "seuser": null,
2019-11-27T18:37:52Z: googlecompute: "shell": null,
2019-11-27T18:37:52Z: googlecompute: "skeleton": null,
2019-11-27T18:37:52Z: googlecompute: "ssh_key_bits": 0,
2019-11-27T18:37:52Z: googlecompute: "ssh_key_comment": "ansible-generated on packer-5ddec243-11d1-1e21-0fce-7f10e418533d",
2019-11-27T18:37:52Z: googlecompute: "ssh_key_file": null,
2019-11-27T18:37:52Z: googlecompute: "ssh_key_passphrase": null,
2019-11-27T18:37:52Z: googlecompute: "ssh_key_type": "rsa",
2019-11-27T18:37:52Z: googlecompute: "state": "present",
2019-11-27T18:37:52Z: googlecompute: "system": false,
2019-11-27T18:37:52Z: googlecompute: "uid": null,
2019-11-27T18:37:52Z: googlecompute: "update_password": "always"
2019-11-27T18:37:52Z: googlecompute: }
2019-11-27T18:37:52Z: googlecompute: },
2019-11-27T18:37:52Z: googlecompute: "name": "ansible",
2019-11-27T18:37:52Z: googlecompute: "shell": "/bin/sh",
2019-11-27T18:37:52Z: googlecompute: "state": "present",
2019-11-27T18:37:52Z: googlecompute: "system": false,
2019-11-27T18:37:52Z: googlecompute: "uid": 1002
2019-11-27T18:37:52Z: googlecompute: }
2019-11-27T18:37:52Z: googlecompute:
2019-11-27T18:37:52Z: googlecompute: TASK [Create example.txt file] *************************************************
2019-11-27T18:37:52Z: googlecompute: task path: /home/ansible/ansible-test/ansible/playbook.yaml:12
2019-11-27T18:37:52Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:52Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'echo ~ansible && sleep 0'"'"''
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 new env request: LANG=C.UTF-8
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 new exec request: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [DEBUG] Opening new ssh session
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [DEBUG] starting remote command: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:37:52 [INFO] 11 bytes written for 'stdout'
2019/11/27 18:37:52 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:52 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:52 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 11 bytes written for 'stdout'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:52Z: googlecompute: <127.0.0.1> (0, '/home/ansible\n', '')
2019-11-27T18:37:52Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:37:52Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=38375 -o 'IdentityFile="/tmp/ansible-key015085847"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/1a2e7d2d5a 127.0.0.1 '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `" && echo ansible-tmp-1574879872.1-105713122089008="` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `" ) && sleep 0'"'"''
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 new env request: LANG=C.UTF-8
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `" && echo ansible-tmp-1574879872.1-105713122089008="` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `" ) && sleep 0'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [DEBUG] Opening new ssh session
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [DEBUG] starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `" && echo ansible-tmp-1574879872.1-105713122089008="` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `" ) && sleep 0'
2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:52 [INFO] 76 bytes written for 'stderr'
2019/11/27 18:37:52 [INFO] RPC client: Communicator ended with: 1
2019/11/27 18:37:52 [INFO] RPC endpoint: Communicator ended with: 1
2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [ERROR] Remote command exited with '1': /bin/sh -c '( umask 77 && mkdir -p "` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `" && echo ansible-tmp-1574879872.1-105713122089008="` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `" ) && sleep 0'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] RPC endpoint: Communicator ended with: 1
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 76 bytes written for 'stderr'
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] RPC client: Communicator ended with: 1
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:37:52Z: googlecompute: <127.0.0.1> (1, '', 'mkdir: cannot create directory \xe2\x80\x98/home/ansible/.ansible\xe2\x80\x99: Permission denied\n')
2019-11-27T18:37:52Z: googlecompute: <127.0.0.1> Failed to connect to the host via ssh: mkdir: cannot create directory ‘/home/ansible/.ansible’: Permission denied
2019-11-27T18:37:52Z: googlecompute: fatal: [default]: UNREACHABLE! => {
2019-11-27T18:37:52Z: googlecompute: "changed": false,
2019-11-27T18:37:52Z: googlecompute: "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 /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `\" && echo ansible-tmp-1574879872.1-105713122089008=\"` echo /home/ansible/.ansible/tmp/ansible-tmp-1574879872.1-105713122089008 `\" ), exited with result 1",
2019-11-27T18:37:52Z: googlecompute: "unreachable": true
2019-11-27T18:37:52Z: googlecompute: }
2019-11-27T18:37:52Z: googlecompute:
2019-11-27T18:37:52Z: googlecompute: PLAY RECAP *********************************************************************
2019-11-27T18:37:52Z: googlecompute: default : ok=2 changed=1 unreachable=1 failed=0 skipped=0 rescued=0 ignored=0
2019-11-27T18:37:52Z: googlecompute:
2019/11/27 18:37:52 [INFO] (telemetry) ending ansible
2019/11/27 18:37:52 packer: 2019/11/27 18:37:52 shutting down the SSH proxy
2019-11-27T18:37:52Z: ==> googlecompute: Provisioning step had errors: Running the cleanup provisioner, if present...
2019-11-27T18:37:52Z: ==> googlecompute: Deleting instance...
2019/11/27 18:37:53 packer: 2019/11/27 18:37:53 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:37:55 packer: 2019/11/27 18:37:55 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:37:57 packer: 2019/11/27 18:37:57 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:00 packer: 2019/11/27 18:38:00 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:02 packer: 2019/11/27 18:38:02 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:04 packer: 2019/11/27 18:38:04 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:07 packer: 2019/11/27 18:38:07 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:09 packer: 2019/11/27 18:38:09 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:11 packer: 2019/11/27 18:38:11 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:13 packer: 2019/11/27 18:38:13 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:16 packer: 2019/11/27 18:38:16 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:18 packer: 2019/11/27 18:38:18 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:20 packer: 2019/11/27 18:38:20 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:23 packer: 2019/11/27 18:38:23 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:25 packer: 2019/11/27 18:38:25 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:27 packer: 2019/11/27 18:38:27 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:29 packer: 2019/11/27 18:38:29 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:32 packer: 2019/11/27 18:38:32 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:34 packer: 2019/11/27 18:38:34 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:36 packer: 2019/11/27 18:38:36 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:38 packer: 2019/11/27 18:38:38 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:41 packer: 2019/11/27 18:38:41 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:43 packer: 2019/11/27 18:38:43 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:45 packer: 2019/11/27 18:38:45 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:48 packer: 2019/11/27 18:38:48 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:50 packer: 2019/11/27 18:38:50 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:52 packer: 2019/11/27 18:38:52 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:55 packer: 2019/11/27 18:38:55 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:57 packer: 2019/11/27 18:38:57 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:38:59 packer: 2019/11/27 18:38:59 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:01 packer: 2019/11/27 18:39:01 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:03 packer: 2019/11/27 18:39:03 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:06 packer: 2019/11/27 18:39:06 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:08 packer: 2019/11/27 18:39:08 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:10 packer: 2019/11/27 18:39:10 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:13 packer: 2019/11/27 18:39:13 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:15 packer: 2019/11/27 18:39:15 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:17 packer: 2019/11/27 18:39:17 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:39:19 packer: 2019/11/27 18:39:19 Retryable error: retrying for state DONE, got RUNNING
2019-11-27T18:39:22Z: googlecompute: Instance has been deleted!
2019-11-27T18:39:22Z: ==> googlecompute: Deleting disk...
2019/11/27 18:39:23 packer: 2019/11/27 18:39:23 Retryable error: retrying for state DONE, got RUNNING
2019-11-27T18:39:25Z: googlecompute: Disk has been deleted!
2019-11-27T18:39:25Z: Build 'googlecompute' errored: Error executing Ansible: Non-zero exit status: exit status 4
==> Some builds didn't complete successfully and had errors:
2019/11/27 18:39:25 [INFO] (telemetry) ending googlecompute
2019/11/27 18:39:25 machine readable: error-count []string{"1"}
==> Some builds didn't complete successfully and had errors:
2019/11/27 18:39:25 machine readable: googlecompute,error []string{"Error executing Ansible: Non-zero exit status: exit status 4"}
==> Builds finished but no artifacts were created.
2019/11/27 18:39:25 [INFO] (telemetry) Finalizing.
--> googlecompute: Error executing Ansible: Non-zero exit status: exit status 4
==> Builds finished but no artifacts were created.
2019/11/27 18:39:25 waiting for all plugin processes to complete...
2019/11/27 18:39:25 /home/ansible/go/bin/packer: plugin process exited
2019/11/27 18:39:25 /home/ansible/go/bin/packer: plugin process exited
ansible@packer-ansible-debug-controller:~/ansible-test$ PACKER_LOG=1 PROJECT_ID=<redacted> TARGET_USER=johndoe ./run.sh
Creating target GCE instance in project '<redacted>' in zone 'europe-west1-b'.
Packer will create and connect via a default non-root 'packer' user on the instance.
Ansible will create a non-default 'johndoe' user.
2019/11/27 18:40:09 [INFO] Packer version: 1.4.5
2019/11/27 18:40:09 Packer Target OS/Arch: linux amd64
2019/11/27 18:40:09 Built with Go Version: go1.13.4
2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 Using internal plugin for osc-chroot
2019/11/27 18:40:09 Using internal plugin for proxmox
2019/11/27 18:40:09 Using internal plugin for googlecompute
2019/11/27 18:40:09 Using internal plugin for osc-bsuvolume
2019/11/27 18:40:09 Using internal plugin for vagrant
2019/11/27 18:40:09 Using internal plugin for vmware-iso
2019/11/27 18:40:09 Using internal plugin for vmware-vmx
2019/11/27 18:40:09 Using internal plugin for amazon-ebsvolume
2019/11/27 18:40:09 Using internal plugin for cloudstack
2019/11/27 18:40:09 Using internal plugin for hyperv-iso
2019/11/27 18:40:09 Using internal plugin for parallels-pvm
2019/11/27 18:40:09 Using internal plugin for qemu
2019/11/27 18:40:09 Using internal plugin for digitalocean
2019/11/27 18:40:09 Using internal plugin for hcloud
2019/11/27 18:40:09 Using internal plugin for ncloud
2019/11/27 18:40:09 Using internal plugin for profitbricks
2019/11/27 18:40:09 Using internal plugin for virtualbox-iso
2019/11/27 18:40:09 Using internal plugin for alicloud-ecs
2019/11/27 18:40:09 Using internal plugin for amazon-instance
2019/11/27 18:40:09 Using internal plugin for azure-arm
2019/11/27 18:40:09 Using internal plugin for hyperv-vmcx
2019/11/27 18:40:09 Using internal plugin for scaleway
2019/11/27 18:40:09 Using internal plugin for ucloud-uhost
2019/11/27 18:40:09 Using internal plugin for virtualbox-ovf
2019/11/27 18:40:09 Using internal plugin for yandex
2019/11/27 18:40:09 Using internal plugin for amazon-ebssurrogate
2019/11/27 18:40:09 Using internal plugin for azure-chroot
2019/11/27 18:40:09 Using internal plugin for docker
2019/11/27 18:40:09 Using internal plugin for lxd
2019/11/27 18:40:09 Using internal plugin for osc-bsu
2019/11/27 18:40:09 Using internal plugin for parallels-iso
2019/11/27 18:40:09 Using internal plugin for virtualbox-vm
2019/11/27 18:40:09 Using internal plugin for amazon-chroot
2019/11/27 18:40:09 Using internal plugin for jdcloud
2019/11/27 18:40:09 Using internal plugin for oneandone
2019/11/27 18:40:09 Using internal plugin for oracle-classic
2019/11/27 18:40:09 Using internal plugin for osc-bsusurrogate
2019/11/27 18:40:09 Using internal plugin for null
2019/11/27 18:40:09 Using internal plugin for openstack
2019/11/27 18:40:09 Using internal plugin for oracle-oci
2019/11/27 18:40:09 Using internal plugin for amazon-ebs
2019/11/27 18:40:09 Using internal plugin for file
2019/11/27 18:40:09 Using internal plugin for hyperone
2019/11/27 18:40:09 Using internal plugin for linode
2019/11/27 18:40:09 Using internal plugin for lxc
2019/11/27 18:40:09 Using internal plugin for tencentcloud-cvm
2019/11/27 18:40:09 Using internal plugin for triton
2019/11/27 18:40:09 Using internal plugin for shell
2019/11/27 18:40:09 Using internal plugin for sleep
2019/11/27 18:40:09 Using internal plugin for ansible
2019/11/27 18:40:09 Using internal plugin for breakpoint
2019/11/27 18:40:09 Using internal plugin for inspec
2019/11/27 18:40:09 Using internal plugin for puppet-masterless
2019/11/27 18:40:09 Using internal plugin for shell-local
2019/11/27 18:40:09 Using internal plugin for windows-restart
2019/11/27 18:40:09 Using internal plugin for ansible-local
2019/11/27 18:40:09 Using internal plugin for chef-client
2019/11/27 18:40:09 Using internal plugin for powershell
2019/11/27 18:40:09 Using internal plugin for salt-masterless
2019/11/27 18:40:09 Using internal plugin for windows-shell
2019/11/27 18:40:09 Using internal plugin for converge
2019/11/27 18:40:09 Using internal plugin for file
2019/11/27 18:40:09 Using internal plugin for puppet-server
2019/11/27 18:40:09 Using internal plugin for chef-solo
2019/11/27 18:40:09 Using internal plugin for vagrant-cloud
2019/11/27 18:40:09 Using internal plugin for vsphere-template
2019/11/27 18:40:09 Using internal plugin for artifice
2019/11/27 18:40:09 Using internal plugin for docker-push
2019/11/27 18:40:09 Using internal plugin for docker-tag
2019/11/27 18:40:09 Using internal plugin for exoscale-import
2019/11/27 18:40:09 Using internal plugin for googlecompute-import
2019/11/27 18:40:09 Using internal plugin for shell-local
2019/11/27 18:40:09 Using internal plugin for alicloud-import
2019/11/27 18:40:09 Using internal plugin for digitalocean-import
2019/11/27 18:40:09 Using internal plugin for docker-save
2019/11/27 18:40:09 Using internal plugin for googlecompute-export
2019/11/27 18:40:09 Using internal plugin for checksum
2019/11/27 18:40:09 Using internal plugin for manifest
2019/11/27 18:40:09 Using internal plugin for vsphere
2019/11/27 18:40:09 Using internal plugin for vagrant
2019/11/27 18:40:09 Using internal plugin for amazon-import
2019/11/27 18:40:09 Using internal plugin for compress
2019/11/27 18:40:09 Using internal plugin for docker-import
2019/11/27 18:40:09 Using internal plugin for ucloud-import
2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 Attempting to open config file: /home/ansible/.packerconfig
2019/11/27 18:40:09 [WARN] Config file doesn't exist: /home/ansible/.packerconfig
2019/11/27 18:40:09 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[alicloud-ecs:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-alicloud-ecs amazon-chroot:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-chroot amazon-ebs:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebs amazon-ebssurrogate:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebssurrogate amazon-ebsvolume:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebsvolume amazon-instance:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-instance azure-arm:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-arm azure-chroot:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-chroot cloudstack:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-cloudstack digitalocean:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-digitalocean docker:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-docker file:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-file googlecompute:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-googlecompute hcloud:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hcloud hyperone:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperone hyperv-iso:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-iso hyperv-vmcx:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-vmcx jdcloud:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-jdcloud linode:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-linode lxc:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxc lxd:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxd ncloud:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-ncloud null:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-null oneandone:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oneandone openstack:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-openstack oracle-classic:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-classic oracle-oci:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-oci osc-bsu:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-osc-bsu osc-bsusurrogate:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-osc-bsusurrogate osc-bsuvolume:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-osc-bsuvolume osc-chroot:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-osc-chroot parallels-iso:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-iso parallels-pvm:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-pvm profitbricks:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-profitbricks proxmox:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-proxmox qemu:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-qemu scaleway:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-scaleway tencentcloud-cvm:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-tencentcloud-cvm triton:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-triton ucloud-uhost:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-ucloud-uhost vagrant:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vagrant virtualbox-iso:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-iso virtualbox-ovf:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-ovf virtualbox-vm:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-vm vmware-iso:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-iso vmware-vmx:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-vmx yandex:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-yandex] PostProcessors:map[alicloud-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-alicloud-import amazon-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-amazon-import artifice:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-artifice checksum:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-checksum compress:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-compress digitalocean-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-digitalocean-import docker-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-import docker-push:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-push docker-save:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-save docker-tag:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-tag exoscale-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-exoscale-import googlecompute-export:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-export googlecompute-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-import manifest:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-manifest shell-local:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-shell-local ucloud-import:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-ucloud-import vagrant:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant vagrant-cloud:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant-cloud vsphere:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere vsphere-template:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere-template] Provisioners:map[ansible:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible ansible-local:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible-local breakpoint:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-breakpoint chef-client:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-client chef-solo:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-solo converge:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-converge file:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-file inspec:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-inspec powershell:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-powershell puppet-masterless:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-masterless puppet-server:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-server salt-masterless:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-salt-masterless shell:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell shell-local:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell-local sleep:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-sleep windows-restart:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-restart windows-shell:/home/ansible/go/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-shell]}
2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 Setting cache directory: /home/ansible/ansible-test/packer_cache
2019/11/27 18:40:09 Loading builder: googlecompute
2019/11/27 18:40:09 Plugin could not be found. Checking same directory as executable.
2019/11/27 18:40:09 Current exe path: /home/ansible/go/bin/packer
2019/11/27 18:40:09 Creating plugin client for path: /home/ansible/go/bin/packer
2019/11/27 18:40:09 Starting plugin: /home/ansible/go/bin/packer []string{"/home/ansible/go/bin/packer", "plugin", "packer-builder-googlecompute"}
2019/11/27 18:40:09 Waiting for RPC address for: /home/ansible/go/bin/packer
2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 [INFO] Packer version: 1.4.5
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Packer Target OS/Arch: linux amd64
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Built with Go Version: go1.13.4
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Attempting to open config file: /home/ansible/.packerconfig
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 [WARN] Config file doesn't exist: /home/ansible/.packerconfig
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Setting cache directory: /home/ansible/ansible-test/packer_cache
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 args: []string{"packer-builder-googlecompute"}
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Plugin minimum port: 10000
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Plugin maximum port: 25000
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Plugin address: unix /tmp/packer-plugin016687237
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Waiting for connection...
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Serving a plugin connection...
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 Loading provisioner: ansible
2019/11/27 18:40:09 Plugin could not be found. Checking same directory as executable.
2019/11/27 18:40:09 Current exe path: /home/ansible/go/bin/packer
2019/11/27 18:40:09 Creating plugin client for path: /home/ansible/go/bin/packer
2019/11/27 18:40:09 Starting plugin: /home/ansible/go/bin/packer []string{"/home/ansible/go/bin/packer", "plugin", "packer-provisioner-ansible"}
2019/11/27 18:40:09 Waiting for RPC address for: /home/ansible/go/bin/packer
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 [INFO] Packer version: 1.4.5
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Packer Target OS/Arch: linux amd64
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Built with Go Version: go1.13.4
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Attempting to open config file: /home/ansible/.packerconfig
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 [WARN] Config file doesn't exist: /home/ansible/.packerconfig
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Setting cache directory: /home/ansible/ansible-test/packer_cache
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 args: []string{"packer-provisioner-ansible"}
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Plugin minimum port: 10000
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Plugin maximum port: 25000
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Plugin address: unix /tmp/packer-plugin329587634
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Waiting for connection...
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Serving a plugin connection...
2019/11/27 18:40:09 packer: 2019/11/27 18:40:09 Detected home directory from env var: /home/ansible
googlecompute output will be in this color.
2019/11/27 18:40:09 Build debug mode: false
2019/11/27 18:40:09 Force build: false
2019/11/27 18:40:09 On error:
2019/11/27 18:40:09 Preparing build: googlecompute
2019/11/27 18:40:10 Waiting on builds to complete...
2019/11/27 18:40:10 Starting build run: googlecompute
2019/11/27 18:40:10 Running builder: googlecompute
2019/11/27 18:40:10 [INFO] (telemetry) Starting builder googlecompute
2019/11/27 18:40:10 packer: 2019/11/27 18:40:10 ansible-playbook version: 2.9.1
2019/11/27 18:40:10 packer: 2019/11/27 18:40:10 [INFO] Requesting Google token via GCE API Default Client Token Source...
2019-11-27T18:40:10Z: ==> googlecompute: Checking image does not exist...
2019/11/27 18:40:10 packer: 2019/11/27 18:40:10 [INFO] Instantiating GCE client...
2019-11-27T18:40:11Z: ==> googlecompute: Creating temporary SSH key for instance...
2019-11-27T18:40:19Z: ==> googlecompute: Using image: ubuntu-1804-bionic-v20191113
2019-11-27T18:40:19Z: ==> googlecompute: Creating instance...
2019-11-27T18:40:19Z: googlecompute: Loading zone: europe-west1-b
2019-11-27T18:40:19Z: googlecompute: Loading machine type: n1-standard-1
2019-11-27T18:40:20Z: googlecompute: Requesting instance creation...
2019-11-27T18:40:21Z: googlecompute: Waiting for creation operation to complete...
2019/11/27 18:40:22 packer: 2019/11/27 18:40:22 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:40:24 packer: 2019/11/27 18:40:24 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:40:26 packer: 2019/11/27 18:40:26 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:40:28 packer: 2019/11/27 18:40:28 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:40:31 packer: 2019/11/27 18:40:31 Retryable error: retrying for state DONE, got RUNNING
2019-11-27T18:40:33Z: googlecompute: Instance has been created!
2019-11-27T18:40:33Z: ==> googlecompute: Waiting for the instance to become running...
2019-11-27T18:40:34Z: googlecompute: IP: 104.199.78.0
2019-11-27T18:40:34Z: ==> googlecompute: Using ssh communicator to connect: 104.199.78.0
2019/11/27 18:40:34 packer: 2019/11/27 18:40:34 [INFO] Waiting for SSH, up to timeout: 5m0s
2019-11-27T18:40:34Z: ==> googlecompute: Waiting for SSH to become available...
2019/11/27 18:40:49 packer: 2019/11/27 18:40:49 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 104.199.78.0:22: i/o timeout
2019/11/27 18:40:55 packer: 2019/11/27 18:40:55 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 104.199.78.0:22: connect: connection refused
2019/11/27 18:41:00 packer: 2019/11/27 18:41:00 [DEBUG] TCP connection to SSH ip/port failed: dial tcp 104.199.78.0:22: connect: connection refused
2019/11/27 18:41:05 packer: 2019/11/27 18:41:05 [INFO] Attempting SSH connection to 104.199.78.0:22...
2019/11/27 18:41:05 packer: 2019/11/27 18:41:05 [DEBUG] Config to &ssh.Config{SSHConfig:(*ssh.ClientConfig)(0xc0007e5ad0), Connection:(func() (net.Conn, error))(0x1202d80), Pty:false, DisableAgentForwarding:false, HandshakeTimeout:0, UseSftp:false, KeepAliveInterval:5000000000, Timeout:0, Tunnels:[]ssh.TunnelSpec(nil)}...
2019/11/27 18:41:05 packer: 2019/11/27 18:41:05 [DEBUG] reconnecting to TCP connection for SSH
2019/11/27 18:41:05 packer: 2019/11/27 18:41:05 [DEBUG] handshaking with SSH
2019/11/27 18:41:05 packer: 2019/11/27 18:41:05 [DEBUG] handshake complete!
2019/11/27 18:41:05 packer: 2019/11/27 18:41:05 [INFO] no local agent socket, will not connect agent
2019-11-27T18:41:05Z: ==> googlecompute: Connected to SSH!
2019/11/27 18:41:05 packer: 2019/11/27 18:41:05 Running the provision hook
2019/11/27 18:41:05 [INFO] (telemetry) Starting provisioner ansible
2019-11-27T18:41:05Z: ==> googlecompute: Provisioning with Ansible...
2019-11-27T18:41:05Z: ==> googlecompute: Executing Ansible: ansible-playbook --extra-vars packer_build_name=googlecompute packer_builder_type=googlecompute -o IdentitiesOnly=yes -i /tmp/packer-provisioner-ansible529102772 /home/ansible/ansible-test/ansible/playbook.yaml -e ansible_ssh_private_key_file=/tmp/ansible-key021302633 -vvv --extra-vars target_user=johndoe
2019/11/27 18:41:05 packer: 2019/11/27 18:41:05 SSH proxy: serving on 127.0.0.1:34193
2019-11-27T18:41:06Z: googlecompute: ansible-playbook 2.9.1
2019-11-27T18:41:06Z: googlecompute: config file = None
2019-11-27T18:41:06Z: googlecompute: configured module search path = [u'/home/ansible/.ansible/plugins/modules', u'/usr/share/ansible/plugins/modules']
2019-11-27T18:41:06Z: googlecompute: ansible python module location = /home/ansible/.local/lib/python2.7/site-packages/ansible
2019-11-27T18:41:06Z: googlecompute: executable location = /home/ansible/.local/bin/ansible-playbook
2019-11-27T18:41:06Z: googlecompute: python version = 2.7.15+ (default, Oct 7 2019, 17:39:04) [GCC 7.4.0]
2019-11-27T18:41:06Z: googlecompute: No config file found; using defaults
2019-11-27T18:41:06Z: googlecompute: host_list declined parsing /tmp/packer-provisioner-ansible529102772 as it did not pass its verify_file() method
2019-11-27T18:41:06Z: googlecompute: script declined parsing /tmp/packer-provisioner-ansible529102772 as it did not pass its verify_file() method
2019-11-27T18:41:06Z: googlecompute: auto declined parsing /tmp/packer-provisioner-ansible529102772 as it did not pass its verify_file() method
2019-11-27T18:41:06Z: googlecompute: Parsed /tmp/packer-provisioner-ansible529102772 inventory source with ini plugin
2019-11-27T18:41:07Z: googlecompute:
2019-11-27T18:41:07Z: googlecompute: PLAYBOOK: playbook.yaml ********************************************************
2019-11-27T18:41:07Z: googlecompute: 1 plays in /home/ansible/ansible-test/ansible/playbook.yaml
2019-11-27T18:41:07Z: googlecompute:
2019-11-27T18:41:07Z: googlecompute: PLAY [all] *********************************************************************
2019-11-27T18:41:07Z: googlecompute:
2019-11-27T18:41:07Z: googlecompute: TASK [Gathering Facts] *********************************************************
2019-11-27T18:41:07Z: googlecompute: task path: /home/ansible/ansible-test/ansible/playbook.yaml:2
2019-11-27T18:41:07Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:07Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'echo ~ansible && sleep 0'"'"''
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 SSH proxy: accepted connection
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 authentication attempt from 127.0.0.1:59122 to 127.0.0.1:34193 as ansible using none
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 authentication attempt from 127.0.0.1:59122 to 127.0.0.1:34193 as ansible using publickey
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 new env request: LANG=C.UTF-8
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 new exec request: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [DEBUG] Opening new ssh session
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [DEBUG] starting remote command: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:41:07 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:41:07 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:07 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:07 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:07 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:07Z: googlecompute: <127.0.0.1> (0, '~ansible\n', "Warning: Permanently added '[127.0.0.1]:34193' (RSA) to the list of known hosts.\r\n")
2019-11-27T18:41:07Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:07Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420 `" && echo ansible-tmp-1574880067.52-24638160373420="` echo ~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420 `" ) && sleep 0'"'"''
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 new env request: LANG=C.UTF-8
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420 `" && echo ansible-tmp-1574880067.52-24638160373420="` echo ~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420 `" ) && sleep 0'
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [DEBUG] Opening new ssh session
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [DEBUG] starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420 `" && echo ansible-tmp-1574880067.52-24638160373420="` echo ~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420 `" ) && sleep 0'
2019/11/27 18:41:07 [INFO] 101 bytes written for 'stdout'
2019/11/27 18:41:07 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:07 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:07 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:07 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] 101 bytes written for 'stdout'
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:07 packer: 2019/11/27 18:41:07 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:07Z: googlecompute: <127.0.0.1> (0, 'ansible-tmp-1574880067.52-24638160373420=~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420\n', '')
2019-11-27T18:41:08Z: googlecompute: <default> Attempting python interpreter discovery
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 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/11/27 18:41:08 packer: 2019/11/27 18:41:08 new env request: LANG=C.UTF-8
2019/11/27 18:41:08 packer: 2019/11/27 18:41: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/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] Opening new ssh session
2019/11/27 18:41:08 packer: 2019/11/27 18:41: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/11/27 18:41:08 [INFO] 66 bytes written for 'stdout'
2019/11/27 18:41:08 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:08 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:08 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 66 bytes written for 'stdout'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> (0, 'PLATFORM\nLinux\nFOUND\n/usr/bin/python3.6\n/usr/bin/python3\nENDFOUND\n', '')
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'/usr/bin/python3.6 && sleep 0'"'"''
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 new env request: LANG=C.UTF-8
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 new exec request: /bin/sh -c '/usr/bin/python3.6 && sleep 0'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 1234 bytes written for 'stdin'
2019/11/27 18:41:08 [INFO] 1234 bytes written for 'stdin'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] Opening new ssh session
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] starting remote command: /bin/sh -c '/usr/bin/python3.6 && sleep 0'
2019/11/27 18:41:08 [INFO] 495 bytes written for 'stdout'
2019/11/27 18:41:08 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:08 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 495 bytes written for 'stdout'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] RPC client: Communicator ended with: 0
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> (0, '{"platform_dist_result": ["Ubuntu", "18.04", "bionic"], "osrelease_content": "NAME=\\"Ubuntu\\"\\nVERSION=\\"18.04.3 LTS (Bionic Beaver)\\"\\nID=ubuntu\\nID_LIKE=debian\\nPRETTY_NAME=\\"Ubuntu 18.04.3 LTS\\"\\nVERSION_ID=\\"18.04\\"\\nHOME_URL=\\"https://www.ubuntu.com/\\"\\nSUPPORT_URL=\\"https://help.ubuntu.com/\\"\\nBUG_REPORT_URL=\\"https://bugs.launchpad.net/ubuntu/\\"\\nPRIVACY_POLICY_URL=\\"https://www.ubuntu.com/legal/terms-and-policies/privacy-policy\\"\\nVERSION_CODENAME=bionic\\nUBUNTU_CODENAME=bionic\\n"}\n', '')
2019-11-27T18:41:08Z: googlecompute: Using module file /home/ansible/.local/lib/python2.7/site-packages/ansible/modules/system/setup.py
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> PUT /home/ansible/.ansible/tmp/ansible-local-15960SGm5pD/tmp2cKclI TO ~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> SSH: EXEC scp -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae /home/ansible/.ansible/tmp/ansible-local-15960SGm5pD/tmp2cKclI '[127.0.0.1]:'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py'"'"''
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 new env request: LANG=C.UTF-8
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 new exec request: scp -t '~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] Opening new ssh session
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] Starting remote scp process: scp -vt ~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] Started SCP session, beginning transfers...
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] scp: Uploading AnsiballZ_setup.py: perms=C0600 size=255015
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 255015 bytes written for 'uploadData'
2019/11/27 18:41:08 [INFO] 255015 bytes written for 'uploadData'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] SCP session complete, closing stdin pipe.
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] Waiting for SSH session to complete.
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] scp stderr (length 38): Sink: C0600 255015 AnsiballZ_setup.py
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'chmod u+x '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/'"'"'"'"'"'"'"'"' '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py'"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 new env request: LANG=C.UTF-8
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 new exec request: /bin/sh -c 'chmod u+x '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/'"'"' '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py'"'"' && sleep 0'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] Opening new ssh session
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] starting remote command: /bin/sh -c 'chmod u+x '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/'"'"' '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py'"'"' && sleep 0'
2019/11/27 18:41:08 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:08 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:08 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:08 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:08Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae -tt 127.0.0.1 '/bin/sh -c '"'"'/usr/bin/python3 '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py'"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 ansible provisioner pty-req request
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 new env request: LANG=C.UTF-8
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 new exec request: /bin/sh -c '/usr/bin/python3 '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py'"'"' && sleep 0'
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] Opening new ssh session
2019/11/27 18:41:08 packer: 2019/11/27 18:41:08 [DEBUG] starting remote command: /bin/sh -c '/usr/bin/python3 '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/AnsiballZ_setup.py'"'"' && sleep 0'
2019/11/27 18:41:09 [INFO] 17112 bytes written for 'stdout'
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:09 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 17112 bytes written for 'stdout'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> (0, '\n{"ansible_facts": {"ansible_user_id": "packer", "ansible_user_uid": 1001, "ansible_user_gid": 1002, "ansible_user_gecos": "", "ansible_user_dir": "/home/packer", "ansible_user_shell": "/bin/bash", "ansible_real_user_id": 1001, "ansible_effective_user_id": 1001, "ansible_real_group_id": 1002, "ansible_effective_group_id": 1002, "ansible_system": "Linux", "ansible_kernel": "5.0.0-1025-gcp", "ansible_kernel_version": "#26~18.04.1-Ubuntu SMP Mon Nov 11 13:09:18 UTC 2019", "ansible_machine": "x86_64", "ansible_python_version": "3.6.8", "ansible_fqdn": "packer-5ddec309-a15d-72cc-7dee-dc0ea092ab0d.c.<redacted>.internal", "ansible_hostname": "packer-5ddec309-a15d-72cc-7dee-dc0ea092ab0d", "ansible_nodename": "packer-5ddec309-a15d-72cc-7dee-dc0ea092ab0d", "ansible_domain": "c.<redacted>.internal", "ansible_userspace_bits": "64", "ansible_architecture": "x86_64", "ansible_userspace_architecture": "x86_64", "ansible_machine_id": "c4bc1d56231daab357fe84e6fbaf0051", "ansible_ssh_host_key_dsa_public": "AAAAB3NzaC1kc3MAAACBAO5jha9PSd7PRQPP5usfwAyL9HQEng2Ps9p9hvndmUX37pXTSuzDc4FS2C/aLiDcYKFWJi5CRyXn8/EyCZHpBTh6giPQGpbcJPm5FI5Q10GvrsPRXNKCYONhEgIjqq07dx4GYe387+y7enBvQ7spWNEakrcMPoGg4N89FbeJAl6nAAAAFQCTuQ2lxCixapJN5JCV3xuZJnx/CQAAAIBRYDEik8rDEKxEhgOLLJQW2FQ8lrArAOGUVX8pj5AsdCd1GRIs/mPJBz4K2QYfeiz8hKzPfke/pgEtz0+ihmui5EQ63syM1WCvEOjLavuI8F7YVyW6e7kChSQC6NWyQ8fWU6MANZwIiC9HKMBp61NnTnXDo2ZekBicfLmvnUkG6QAAAIB8nLzYfsp90eqA/a4DAoBtvHWvTb3lHr0lxXpTk/lXYoD0wkWuGwBTscJSNByyqDFcLhSTCVVYqp9YtGkzvEXw7tr0+Jh3mSlQOw1wboIEpmaVh6z+Wo3A4XxR+afytUU99a89hlpae1K1b3DJ+HXyH3+jW4DLc9C6eC5B9mbdaw==", "ansible_ssh_host_key_rsa_public": "AAAAB3NzaC1yc2EAAAADAQABAAABAQDyd78qHpnNb8BB1hddqfEwZHfSzTuONE8IB++tywRoBgqvi5ICInbDsczU/r2MseUQYQ+TNXtwQmRGyeZhhcJNJbz2GOcCYiojPz1yRuSshut4aWLLqiKDtxB9vQf+w3GfiGajOYpLORVKpvLVpKqRCKtcqbv+KRyBmWj/7QOq0FQ8RefUy8W64xVsKS+Zba7HyWD9oHtaHTTqlTmUmHZ0PpC7+/2F5vq99sa4EJXg2MAQ1B/VIu6FKdOxSEyGLUDm8NrMTB9rleaHU5vVqjJmUa1777SKLVO7nk17XIIjelBD5CGCtrhGpl5gZvGN4MhZUo+JmD724XZmZKsF0SIj", "ansible_ssh_host_key_ecdsa_public": "AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBM5Xh+EIJ7aS5BqJ2A7GDb+qrajeMK4AXAtGEKrkCfS+IpuuJfNA3LalFEKQ+/xnPCMOgqyJE8t1skWOudg0+zU=", "ansible_ssh_host_key_ed25519_public": "AAAAC3NzaC1lZDI1NTE5AAAAIG07VUJcRO6OuoJviqrSZUrZcMNLSg5Jcvviipw75jlP", "ansible_hostnqn": "", "ansible_system_capabilities_enforced": "True", "ansible_system_capabilities": [""], "ansible_distribution": "Ubuntu", "ansible_distribution_release": "bionic", "ansible_distribution_version": "18.04", "ansible_distribution_major_version": "18", "ansible_distribution_file_path": "/etc/os-release", "ansible_distribution_file_variety": "Debian", "ansible_distribution_file_parsed": true, "ansible_os_family": "Debian", "ansible_apparmor": {"status": "enabled"}, "ansible_dns": {"nameservers": ["127.0.0.53"], "options": {"edns0": true}, "search": ["c.<redacted>.internal", "google.internal"]}, "ansible_cmdline": {"BOOT_IMAGE": "/boot/vmlinuz-5.0.0-1025-gcp", "root": "LABEL=cloudimg-rootfs", "ro": true, "scsi_mod.use_blk_mq": "Y", "console": "ttyS0"}, "ansible_proc_cmdline": {"BOOT_IMAGE": "/boot/vmlinuz-5.0.0-1025-gcp", "root": "LABEL=cloudimg-rootfs", "ro": true, "scsi_mod.use_blk_mq": "Y", "console": "ttyS0"}, "ansible_date_time": {"year": "2019", "month": "11", "weekday": "Wednesday", "weekday_number": "3", "weeknumber": "47", "day": "27", "hour": "18", "minute": "41", "second": "09", "epoch": "1574880069", "date": "2019-11-27", "time": "18:41:09", "iso8601_micro": "2019-11-27T18:41:09.124289Z", "iso8601": "2019-11-27T18:41:09Z", "iso8601_basic": "20191127T184109124202", "iso8601_basic_short": "20191127T184109", "tz": "UTC", "tz_offset": "+0000"}, "ansible_selinux": {"status": "Missing selinux Python library"}, "ansible_selinux_python_present": false, "ansible_is_chroot": false, "ansible_lsb": {"id": "Ubuntu", "description": "Ubuntu 18.04.3 LTS", "release": "18.04", "codename": "bionic", "major_release": "18"}, "ansible_virtualization_role": "guest", "ansible_virtualization_type": "kvm", "ansible_env": {"MAIL": "/var/mail/packer", "USER": "packer", "SSH_CLIENT": "35.205.33.202 48688 22", "SHLVL": "1", "HOME": "/home/packer", "LOGNAME": "packer", "_": "/bin/sh", "XDG_SESSION_ID": "1", "PATH": "/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games", "XDG_RUNTIME_DIR": "/run/user/1001", "LANG": "C.UTF-8", "SHELL": "/bin/bash", "PWD": "/home/packer", "SSH_CONNECTION": "35.205.33.202 48688 10.132.0.51 22"}, "ansible_fips": false, "ansible_iscsi_iqn": "", "ansible_local": {}, "ansible_fibre_channel_wwn": [], "ansible_python": {"version": {"major": 3, "minor": 6, "micro": 8, "releaselevel": "final", "serial": 0}, "version_info": [3, 6, 8, "final", 0], "executable": "/usr/bin/python3", "has_sslcontext": true, "type": "cpython"}, "ansible_interfaces": ["lo", "ens4"], "ansible_lo": {"device": "lo", "mtu": 65536, "active": true, "type": "loopback", "promisc": false, "ipv4": {"address": "127.0.0.1", "broadcast": "host", "netmask": "255.0.0.0", "network": "127.0.0.0"}, "ipv6": [{"address": "::1", "prefix": "128", "scope": "host"}], "features": {"rx_checksumming": "on [fixed]", "tx_checksumming": "on", "tx_checksum_ipv4": "off [fixed]", "tx_checksum_ip_generic": "on [fixed]", "tx_checksum_ipv6": "off [fixed]", "tx_checksum_fcoe_crc": "off [fixed]", "tx_checksum_sctp": "on [fixed]", "scatter_gather": "on", "tx_scatter_gather": "on [fixed]", "tx_scatter_gather_fraglist": "on [fixed]", "tcp_segmentation_offload": "on", "tx_tcp_segmentation": "on", "tx_tcp_ecn_segmentation": "on", "tx_tcp_mangleid_segmentation": "on", "tx_tcp6_segmentation": "on", "udp_fragmentation_offload": "off", "generic_segmentation_offload": "on", "generic_receive_offload": "on", "large_receive_offload": "off [fixed]", "rx_vlan_offload": "off [fixed]", "tx_vlan_offload": "off [fixed]", "ntuple_filters": "off [fixed]", "receive_hashing": "off [fixed]", "highdma": "on [fixed]", "rx_vlan_filter": "off [fixed]", "vlan_challenged": "on [fixed]", "tx_lockless": "on [fixed]", "netns_local": "on [fixed]", "tx_gso_robust": "off [fixed]", "tx_fcoe_segmentation": "off [fixed]", "tx_gre_segmentation": "off [fixed]", "tx_gre_csum_segmentation": "off [fixed]", "tx_ipxip4_segmentation": "off [fixed]", "tx_ipxip6_segmentation": "off [fixed]", "tx_udp_tnl_segmentation": "off [fixed]", "tx_udp_tnl_csum_segmentation": "off [fixed]", "tx_gso_partial": "off [fixed]", "tx_sctp_segmentation": "on", "tx_esp_segmentation": "off [fixed]", "tx_udp_segmentation": "off [fixed]", "fcoe_mtu": "off [fixed]", "tx_nocache_copy": "off [fixed]", "loopback": "on [fixed]", "rx_fcs": "off [fixed]", "rx_all": "off [fixed]", "tx_vlan_stag_hw_insert": "off [fixed]", "rx_vlan_stag_hw_parse": "off [fixed]", "rx_vlan_stag_filter": "off [fixed]", "l2_fwd_offload": "off [fixed]", "hw_tc_offload": "off [fixed]", "esp_hw_offload": "off [fixed]", "esp_tx_csum_hw_offload": "off [fixed]", "rx_udp_tunnel_port_offload": "off [fixed]", "tls_hw_tx_offload": "off [fixed]", "tls_hw_rx_offload": "off [fixed]", "rx_gro_hw": "off [fixed]", "tls_hw_record": "off [fixed]"}, "timestamping": ["tx_software", "rx_software", "software"], "hw_timestamp_filters": []}, "ansible_ens4": {"device": "ens4", "macaddress": "42:01:0a:84:00:33", "mtu": 1460, "active": true, "module": "virtio_net", "type": "ether", "pciid": "virtio1", "speed": -1, "promisc": false, "ipv4": {"address": "10.132.0.51", "broadcast": "global", "netmask": "255.255.255.255", "network": "10.132.0.51"}, "ipv6": [{"address": "fe80::4001:aff:fe84:33", "prefix": "64", "scope": "link"}], "features": {"rx_checksumming": "on [fixed]", "tx_checksumming": "on", "tx_checksum_ipv4": "off [fixed]", "tx_checksum_ip_generic": "on", "tx_checksum_ipv6": "off [fixed]", "tx_checksum_fcoe_crc": "off [fixed]", "tx_checksum_sctp": "off [fixed]", "scatter_gather": "on", "tx_scatter_gather": "on", "tx_scatter_gather_fraglist": "off [fixed]", "tcp_segmentation_offload": "on", "tx_tcp_segmentation": "on", "tx_tcp_ecn_segmentation": "off [fixed]", "tx_tcp_mangleid_segmentation": "off", "tx_tcp6_segmentation": "on", "udp_fragmentation_offload": "off", "generic_segmentation_offload": "on", "generic_receive_offload": "on", "large_receive_offload": "on", "rx_vlan_offload": "off [fixed]", "tx_vlan_offload": "off [fixed]", "ntuple_filters": "off [fixed]", "receive_hashing": "off [fixed]", "highdma": "on [fixed]", "rx_vlan_filter": "off [fixed]", "vlan_challenged": "off [fixed]", "tx_lockless": "off [fixed]", "netns_local": "off [fixed]", "tx_gso_robust": "on [fixed]", "tx_fcoe_segmentation": "off [fixed]", "tx_gre_segmentation": "off [fixed]", "tx_gre_csum_segmentation": "off [fixed]", "tx_ipxip4_segmentation": "off [fixed]", "tx_ipxip6_segmentation": "off [fixed]", "tx_udp_tnl_segmentation": "off [fixed]", "tx_udp_tnl_csum_segmentation": "off [fixed]", "tx_gso_partial": "off [fixed]", "tx_sctp_segmentation": "off [fixed]", "tx_esp_segmentation": "off [fixed]", "tx_udp_segmentation": "off [fixed]", "fcoe_mtu": "off [fixed]", "tx_nocache_copy": "off", "loopback": "off [fixed]", "rx_fcs": "off [fixed]", "rx_all": "off [fixed]", "tx_vlan_stag_hw_insert": "off [fixed]", "rx_vlan_stag_hw_parse": "off [fixed]", "rx_vlan_stag_filter": "off [fixed]", "l2_fwd_offload": "off [fixed]", "hw_tc_offload": "off [fixed]", "esp_hw_offload": "off [fixed]", "esp_tx_csum_hw_offload": "off [fixed]", "rx_udp_tunnel_port_offload": "off [fixed]", "tls_hw_tx_offload": "off [fixed]", "tls_hw_rx_offload": "off [fixed]", "rx_gro_hw": "off [fixed]", "tls_hw_record": "off [fixed]"}, "timestamping": ["tx_software", "rx_software", "software"], "hw_timestamp_filters": []}, "ansible_default_ipv4": {"gateway": "10.132.0.1", "interface": "ens4", "address": "10.132.0.51", "broadcast": "global", "netmask": "255.255.255.255", "network": "10.132.0.51", "macaddress": "42:01:0a:84:00:33", "mtu": 1460, "type": "ether", "alias": "ens4"}, "ansible_default_ipv6": {}, "ansible_all_ipv4_addresses": ["10.132.0.51"], "ansible_all_ipv6_addresses": ["fe80::4001:aff:fe84:33"], "ansible_processor": ["0", "GenuineIntel", "Intel(R) Xeon(R) CPU @ 2.30GHz"], "ansible_processor_count": 1, "ansible_processor_cores": 1, "ansible_processor_threads_per_core": 1, "ansible_processor_vcpus": 1, "ansible_memtotal_mb": 3690, "ansible_memfree_mb": 3102, "ansible_swaptotal_mb": 0, "ansible_swapfree_mb": 0, "ansible_memory_mb": {"real": {"total": 3690, "used": 588, "free": 3102}, "nocache": {"free": 3454, "used": 236}, "swap": {"total": 0, "free": 0, "used": 0, "cached": 0}}, "ansible_bios_date": "01/01/2011", "ansible_bios_version": "Google", "ansible_form_factor": "Other", "ansible_product_name": "Google Compute Engine", "ansible_product_serial": "NA", "ansible_product_uuid": "NA", "ansible_product_version": "NA", "ansible_system_vendor": "Google", "ansible_devices": {"loop1": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop6": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop4": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop2": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop0": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "4096", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "182480", "sectorsize": "512", "size": "89.10 MB", "host": "", "holders": []}, "loop7": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "sda": {"virtual": 1, "links": {"ids": ["google-persistent-disk-0", "scsi-0Google_PersistentDisk_persistent-disk-0"], "uuids": [], "labels": [], "masters": []}, "vendor": "Google", "model": "PersistentDisk", "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "4096", "partitions": {"sda14": {"links": {"ids": ["google-persistent-disk-0-part14", "scsi-0Google_PersistentDisk_persistent-disk-0-part14"], "uuids": [], "labels": [], "masters": []}, "start": "2048", "sectors": "8192", "sectorsize": 512, "size": "4.00 MB", "uuid": null, "holders": []}, "sda15": {"links": {"ids": ["google-persistent-disk-0-part15", "scsi-0Google_PersistentDisk_persistent-disk-0-part15"], "uuids": ["84B5-FED0"], "labels": ["UEFI"], "masters": []}, "start": "10240", "sectors": "217088", "sectorsize": 512, "size": "106.00 MB", "uuid": "84B5-FED0", "holders": []}, "sda1": {"links": {"ids": ["google-persistent-disk-0-part1", "scsi-0Google_PersistentDisk_persistent-disk-0-part1"], "uuids": ["e0c095ca-21b2-4a02-bbea-0dc95c07cfc8"], "labels": ["cloudimg-rootfs"], "masters": []}, "start": "227328", "sectors": "20744159", "sectorsize": 512, "size": "9.89 GB", "uuid": "e0c095ca-21b2-4a02-bbea-0dc95c07cfc8", "holders": []}}, "rotational": "1", "scheduler_mode": "none", "sectors": "20971520", "sectorsize": "512", "size": "10.00 GB", "host": "Non-VGA unclassified device: Red Hat, Inc. Virtio SCSI", "holders": []}, "loop5": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}, "loop3": {"virtual": 1, "links": {"ids": [], "uuids": [], "labels": [], "masters": []}, "vendor": null, "model": null, "sas_address": null, "sas_device_handle": null, "removable": "0", "support_discard": "0", "partitions": {}, "rotational": "1", "scheduler_mode": "none", "sectors": "0", "sectorsize": "512", "size": "0.00 Bytes", "host": "", "holders": []}}, "ansible_device_links": {"ids": {"sda14": ["google-persistent-disk-0-part14", "scsi-0Google_PersistentDisk_persistent-disk-0-part14"], "sda15": ["google-persistent-disk-0-part15", "scsi-0Google_PersistentDisk_persistent-disk-0-part15"], "sda1": ["google-persistent-disk-0-part1", "scsi-0Google_PersistentDisk_persistent-disk-0-part1"], "sda": ["google-persistent-disk-0", "scsi-0Google_PersistentDisk_persistent-disk-0"]}, "uuids": {"sda15": ["84B5-FED0"], "sda1": ["e0c095ca-21b2-4a02-bbea-0dc95c07cfc8"]}, "labels": {"sda15": ["UEFI"], "sda1": ["cloudimg-rootfs"]}, "masters": {}}, "ansible_uptime_seconds": 24, "ansible_mounts": [{"mount": "/", "device": "/dev/sda1", "fstype": "ext4", "options": "rw,relatime", "size_total": 10222829568, "size_available": 8990470144, "block_size": 4096, "block_total": 2495808, "block_available": 2194939, "block_used": 300869, "inode_total": 1290240, "inode_available": 1222067, "inode_used": 68173, "uuid": "e0c095ca-21b2-4a02-bbea-0dc95c07cfc8"}, {"mount": "/boot/efi", "device": "/dev/sda15", "fstype": "vfat", "options": "rw,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro", "size_total": 109422592, "size_available": 105652736, "block_size": 512, "block_total": 213716, "block_available": 206353, "block_used": 7363, "inode_total": 0, "inode_available": 0, "inode_used": 0, "uuid": "84B5-FED0"}, {"mount": "/snap/core/8039", "device": "/dev/loop0", "fstype": "squashfs", "options": "ro,nodev,relatime", "size_total": 93454336, "size_available": 0, "block_size": 131072, "block_total": 713, "block_available": 0, "block_used": 713, "inode_total": 12842, "inode_available": 0, "inode_used": 12842, "uuid": "N/A"}], "ansible_service_mgr": "systemd", "ansible_pkg_mgr": "apt", "gather_subset": ["all"], "module_setup": true}, "invocation": {"module_args": {"gather_timeout": 10, "gather_subset": ["all"], "filter": "*", "fact_path": "/etc/ansible/facts.d"}}}\n', 'Shared connection to 127.0.0.1 closed.\r\n')
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'rm -f -r '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/'"'"'"'"'"'"'"'"' > /dev/null 2>&1 && sleep 0'"'"''
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 new env request: LANG=C.UTF-8
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 new exec request: /bin/sh -c 'rm -f -r '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/'"'"' > /dev/null 2>&1 && sleep 0'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [DEBUG] Opening new ssh session
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [DEBUG] starting remote command: /bin/sh -c 'rm -f -r '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880067.52-24638160373420/'"'"' > /dev/null 2>&1 && sleep 0'
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> (0, '', '')
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:09 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:09Z: googlecompute: ok: [default]
2019-11-27T18:41:09Z: googlecompute: META: ran handlers
2019-11-27T18:41:09Z: googlecompute:
2019-11-27T18:41:09Z: googlecompute: TASK [Setup 'johndoe' user] ****************************************************
2019-11-27T18:41:09Z: googlecompute: task path: /home/ansible/ansible-test/ansible/playbook.yaml:7
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'echo ~ansible && sleep 0'"'"''
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 new env request: LANG=C.UTF-8
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 new exec request: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [DEBUG] Opening new ssh session
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [DEBUG] starting remote command: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:41:09 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:09 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> (0, '~ansible\n', '')
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632 `" && echo ansible-tmp-1574880069.71-105827950380632="` echo ~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632 `" ) && sleep 0'"'"''
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 new env request: LANG=C.UTF-8
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632 `" && echo ansible-tmp-1574880069.71-105827950380632="` echo ~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632 `" ) && sleep 0'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [DEBUG] Opening new ssh session
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [DEBUG] starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo ~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632 `" && echo ansible-tmp-1574880069.71-105827950380632="` echo ~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632 `" ) && sleep 0'
2019/11/27 18:41:09 [INFO] 103 bytes written for 'stdout'
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:09 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 103 bytes written for 'stdout'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> (0, 'ansible-tmp-1574880069.71-105827950380632=~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632\n', '')
2019-11-27T18:41:09Z: googlecompute: Using module file /home/ansible/.local/lib/python2.7/site-packages/ansible/modules/system/user.py
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> PUT /home/ansible/.ansible/tmp/ansible-local-15960SGm5pD/tmpwT1FOW TO ~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py
2019-11-27T18:41:09Z: googlecompute: <127.0.0.1> SSH: EXEC scp -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae /home/ansible/.ansible/tmp/ansible-local-15960SGm5pD/tmpwT1FOW '[127.0.0.1]:'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py'"'"''
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 new env request: LANG=C.UTF-8
2019/11/27 18:41:09 packer: 2019/11/27 18:41:09 new exec request: scp -t '~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Starting remote scp process: scp -vt ~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 127770 bytes written for 'uploadData'
2019/11/27 18:41:10 [INFO] 127770 bytes written for 'uploadData'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Started SCP session, beginning transfers...
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] scp: Uploading AnsiballZ_user.py: perms=C0600 size=127770
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] SCP session complete, closing stdin pipe.
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Waiting for SSH session to complete.
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] scp stderr (length 37): Sink: C0600 127770 AnsiballZ_user.py
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'chmod u+x '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/'"'"'"'"'"'"'"'"' '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py'"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new env request: LANG=C.UTF-8
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new exec request: /bin/sh -c 'chmod u+x '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/'"'"' '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py'"'"' && sleep 0'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] starting remote command: /bin/sh -c 'chmod u+x '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/'"'"' '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py'"'"' && sleep 0'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae -tt 127.0.0.1 '/bin/sh -c '"'"'sudo -H -S -n -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-mnllgbxkhpehejknhipiihnqdhxmtqqs ; /usr/bin/python3 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 ansible provisioner pty-req request
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new env request: LANG=C.UTF-8
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new exec request: /bin/sh -c 'sudo -H -S -n -u root /bin/sh -c '"'"'echo BECOME-SUCCESS-mnllgbxkhpehejknhipiihnqdhxmtqqs ; /usr/bin/python3 '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py'"'"'"'"'"'"'"'"''"'"' && sleep 0'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] starting remote command: /bin/sh -c 'sudo -H -S -n -u root /bin/sh -c '"'"'echo BECOME-SUCCESS-mnllgbxkhpehejknhipiihnqdhxmtqqs ; /usr/bin/python3 '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/AnsiballZ_user.py'"'"'"'"'"'"'"'"''"'"' && sleep 0'
2019-11-27T18:41:10Z: googlecompute: Escalation succeeded
2019/11/27 18:41:10 [INFO] 955 bytes written for 'stdout'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 955 bytes written for 'stdout'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> (0, '\n{"name": "johndoe", "state": "present", "system": false, "create_home": true, "changed": true, "uid": 1002, "group": 1003, "comment": "", "home": "/home/johndoe", "shell": "/bin/sh", "invocation": {"module_args": {"name": "johndoe", "state": "present", "non_unique": false, "force": false, "remove": false, "create_home": true, "system": false, "move_home": false, "append": false, "ssh_key_bits": 0, "ssh_key_type": "rsa", "ssh_key_comment": "ansible-generated on packer-5ddec309-a15d-72cc-7dee-dc0ea092ab0d", "update_password": "always", "uid": null, "group": null, "groups": null, "comment": null, "home": null, "shell": null, "password": null, "login_class": null, "hidden": null, "seuser": null, "skeleton": null, "generate_ssh_key": null, "ssh_key_file": null, "ssh_key_passphrase": null, "expires": null, "password_lock": null, "local": null, "profile": null, "authorization": null, "role": null}}}\n', 'Shared connection to 127.0.0.1 closed.\r\n')
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'rm -f -r '"'"'"'"'"'"'"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/'"'"'"'"'"'"'"'"' > /dev/null 2>&1 && sleep 0'"'"''
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new env request: LANG=C.UTF-8
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new exec request: /bin/sh -c 'rm -f -r '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/'"'"' > /dev/null 2>&1 && sleep 0'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] starting remote command: /bin/sh -c 'rm -f -r '"'"'~ansible/.ansible/tmp/ansible-tmp-1574880069.71-105827950380632/'"'"' > /dev/null 2>&1 && sleep 0'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:41:10Z: googlecompute: changed: [default] => {
2019-11-27T18:41:10Z: googlecompute: "changed": true,
2019-11-27T18:41:10Z: googlecompute: "comment": "",
2019-11-27T18:41:10Z: googlecompute: "create_home": true,
2019-11-27T18:41:10Z: googlecompute: "group": 1003,
2019-11-27T18:41:10Z: googlecompute: "home": "/home/johndoe",
2019-11-27T18:41:10Z: googlecompute: "invocation": {
2019-11-27T18:41:10Z: googlecompute: "module_args": {
2019-11-27T18:41:10Z: googlecompute: "append": false,
2019-11-27T18:41:10Z: googlecompute: "authorization": null,
2019-11-27T18:41:10Z: googlecompute: "comment": null,
2019-11-27T18:41:10Z: googlecompute: "create_home": true,
2019-11-27T18:41:10Z: googlecompute: "expires": null,
2019-11-27T18:41:10Z: googlecompute: "force": false,
2019-11-27T18:41:10Z: googlecompute: "generate_ssh_key": null,
2019-11-27T18:41:10Z: googlecompute: "group": null,
2019-11-27T18:41:10Z: googlecompute: "groups": null,
2019-11-27T18:41:10Z: googlecompute: "hidden": null,
2019-11-27T18:41:10Z: googlecompute: "home": null,
2019-11-27T18:41:10Z: googlecompute: "local": null,
2019-11-27T18:41:10Z: googlecompute: "login_class": null,
2019-11-27T18:41:10Z: googlecompute: "move_home": false,
2019-11-27T18:41:10Z: googlecompute: "name": "johndoe",
2019-11-27T18:41:10Z: googlecompute: "non_unique": false,
2019-11-27T18:41:10Z: googlecompute: "password": null,
2019-11-27T18:41:10Z: googlecompute: "password_lock": null,
2019-11-27T18:41:10Z: googlecompute: "profile": null,
2019-11-27T18:41:10Z: googlecompute: "remove": false,
2019-11-27T18:41:10Z: googlecompute: "role": null,
2019-11-27T18:41:10Z: googlecompute: "seuser": null,
2019-11-27T18:41:10Z: googlecompute: "shell": null,
2019-11-27T18:41:10Z: googlecompute: "skeleton": null,
2019-11-27T18:41:10Z: googlecompute: "ssh_key_bits": 0,
2019-11-27T18:41:10Z: googlecompute: "ssh_key_comment": "ansible-generated on packer-5ddec309-a15d-72cc-7dee-dc0ea092ab0d",
2019-11-27T18:41:10Z: googlecompute: "ssh_key_file": null,
2019-11-27T18:41:10Z: googlecompute: "ssh_key_passphrase": null,
2019-11-27T18:41:10Z: googlecompute: "ssh_key_type": "rsa",
2019-11-27T18:41:10Z: googlecompute: "state": "present",
2019-11-27T18:41:10Z: googlecompute: "system": false,
2019-11-27T18:41:10Z: googlecompute: "uid": null,
2019-11-27T18:41:10Z: googlecompute: "update_password": "always"
2019-11-27T18:41:10Z: googlecompute: }
2019-11-27T18:41:10Z: googlecompute: },
2019-11-27T18:41:10Z: googlecompute: "name": "johndoe",
2019-11-27T18:41:10Z: googlecompute: "shell": "/bin/sh",
2019-11-27T18:41:10Z: googlecompute: "state": "present",
2019-11-27T18:41:10Z: googlecompute: "system": false,
2019-11-27T18:41:10Z: googlecompute: "uid": 1002
2019-11-27T18:41:10Z: googlecompute: }
2019-11-27T18:41:10Z: googlecompute:
2019-11-27T18:41:10Z: googlecompute: TASK [Create example.txt file] *************************************************
2019-11-27T18:41:10Z: googlecompute: task path: /home/ansible/ansible-test/ansible/playbook.yaml:12
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'echo ~ansible && sleep 0'"'"''
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new env request: LANG=C.UTF-8
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new exec request: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] starting remote command: /bin/sh -c 'echo ~ansible && sleep 0'
2019/11/27 18:41:10 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 6 bytes written for 'stdout'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> (0, '~ansible\n', '')
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo /var/tmp/ansible-tmp-1574880070.43-227613384334915 `" && echo ansible-tmp-1574880070.43-227613384334915="` echo /var/tmp/ansible-tmp-1574880070.43-227613384334915 `" ) && sleep 0'"'"''
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new env request: LANG=C.UTF-8
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo /var/tmp/ansible-tmp-1574880070.43-227613384334915 `" && echo ansible-tmp-1574880070.43-227613384334915="` echo /var/tmp/ansible-tmp-1574880070.43-227613384334915 `" ) && sleep 0'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo /var/tmp/ansible-tmp-1574880070.43-227613384334915 `" && echo ansible-tmp-1574880070.43-227613384334915="` echo /var/tmp/ansible-tmp-1574880070.43-227613384334915 `" ) && sleep 0'
2019/11/27 18:41:10 [INFO] 93 bytes written for 'stdout'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 93 bytes written for 'stdout'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> (0, 'ansible-tmp-1574880070.43-227613384334915=/var/tmp/ansible-tmp-1574880070.43-227613384334915\n', '')
2019-11-27T18:41:10Z: googlecompute: Using module file /home/ansible/.local/lib/python2.7/site-packages/ansible/modules/commands/command.py
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> PUT /home/ansible/.ansible/tmp/ansible-local-15960SGm5pD/tmpMelZZL TO /var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> SSH: EXEC scp -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae /home/ansible/.ansible/tmp/ansible-local-15960SGm5pD/tmpMelZZL '[127.0.0.1]:/var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new env request: LANG=C.UTF-8
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new exec request: scp -t /var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 [INFO] 107842 bytes written for 'uploadData'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 107842 bytes written for 'uploadData'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Starting remote scp process: scp -vt /var/tmp/ansible-tmp-1574880070.43-227613384334915
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Started SCP session, beginning transfers...
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] scp: Uploading AnsiballZ_command.py: perms=C0600 size=107842
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] SCP session complete, closing stdin pipe.
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Waiting for SSH session to complete.
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] scp stderr (length 40): Sink: C0600 107842 AnsiballZ_command.py
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'setfacl -m u:johndoe:r-x /var/tmp/ansible-tmp-1574880070.43-227613384334915/ /var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py && sleep 0'"'"''
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new env request: LANG=C.UTF-8
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new exec request: /bin/sh -c 'setfacl -m u:johndoe:r-x /var/tmp/ansible-tmp-1574880070.43-227613384334915/ /var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py && sleep 0'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] starting remote command: /bin/sh -c 'setfacl -m u:johndoe:r-x /var/tmp/ansible-tmp-1574880070.43-227613384334915/ /var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py && sleep 0'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:10Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae -tt 127.0.0.1 '/bin/sh -c '"'"'sudo -H -S -n -u johndoe /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-cilktmrwzbxbnipixdcsfbyvsdsokxsp ; /usr/bin/python3 /var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py'"'"'"'"'"'"'"'"' && sleep 0'"'"''
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 ansible provisioner pty-req request
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new env request: LANG=C.UTF-8
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 new exec request: /bin/sh -c 'sudo -H -S -n -u johndoe /bin/sh -c '"'"'echo BECOME-SUCCESS-cilktmrwzbxbnipixdcsfbyvsdsokxsp ; /usr/bin/python3 /var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py'"'"' && sleep 0'
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] Opening new ssh session
2019/11/27 18:41:10 packer: 2019/11/27 18:41:10 [DEBUG] starting remote command: /bin/sh -c 'sudo -H -S -n -u johndoe /bin/sh -c '"'"'echo BECOME-SUCCESS-cilktmrwzbxbnipixdcsfbyvsdsokxsp ; /usr/bin/python3 /var/tmp/ansible-tmp-1574880070.43-227613384334915/AnsiballZ_command.py'"'"' && sleep 0'
2019-11-27T18:41:10Z: googlecompute: Escalation succeeded
2019/11/27 18:41:11 [INFO] 801 bytes written for 'stdout'
2019/11/27 18:41:11 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:11 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:11 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:11 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] 801 bytes written for 'stdout'
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:11Z: googlecompute: <127.0.0.1> (0, '\n{"cmd": ["touch", "/home/johndoe/example.txt"], "stdout": "", "stderr": "", "rc": 0, "start": "2019-11-27 18:41:11.049416", "end": "2019-11-27 18:41:11.062358", "delta": "0:00:00.012942", "changed": true, "invocation": {"module_args": {"_raw_params": "touch \\"/home/johndoe/example.txt\\"", "warn": true, "_uses_shell": false, "stdin_add_newline": true, "strip_empty_ends": true, "argv": null, "chdir": null, "executable": null, "creates": null, "removes": null, "stdin": null}}, "warnings": ["Consider using the file module with state=touch rather than running \'touch\'. If you need to use command because file is insufficient you can add \'warn: false\' to this command task or set \'command_warnings=False\' in ansible.cfg to get rid of this message."]}\n', 'Shared connection to 127.0.0.1 closed.\r\n')
2019-11-27T18:41:11Z: googlecompute: <127.0.0.1> ESTABLISH SSH CONNECTION FOR USER: ansible
2019-11-27T18:41:11Z: googlecompute: <127.0.0.1> SSH: EXEC ssh -C -o ControlMaster=auto -o ControlPersist=60s -o StrictHostKeyChecking=no -o Port=34193 -o 'IdentityFile="/tmp/ansible-key021302633"' -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o 'User="ansible"' -o ConnectTimeout=10 -o ControlPath=/home/ansible/.ansible/cp/ded193dcae 127.0.0.1 '/bin/sh -c '"'"'rm -f -r /var/tmp/ansible-tmp-1574880070.43-227613384334915/ > /dev/null 2>&1 && sleep 0'"'"''
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 new env request: LANG=C.UTF-8
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 new exec request: /bin/sh -c 'rm -f -r /var/tmp/ansible-tmp-1574880070.43-227613384334915/ > /dev/null 2>&1 && sleep 0'
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [DEBUG] Opening new ssh session
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [DEBUG] starting remote command: /bin/sh -c 'rm -f -r /var/tmp/ansible-tmp-1574880070.43-227613384334915/ > /dev/null 2>&1 && sleep 0'
2019/11/27 18:41:11 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:11 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:11 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:11 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:11 [INFO] 0 bytes written for 'stdin'
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] RPC endpoint: Communicator ended with: 0
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] 0 bytes written for 'stdout'
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] 0 bytes written for 'stderr'
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] RPC client: Communicator ended with: 0
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 [INFO] 0 bytes written for 'stdin'
2019-11-27T18:41:11Z: googlecompute: <127.0.0.1> (0, '', '')
2019-11-27T18:41:11Z: googlecompute: [WARNING]: Consider using the file module with state=touch rather than running
2019-11-27T18:41:11Z: googlecompute: 'touch'. If you need to use command because file is insufficient you can add
2019-11-27T18:41:11Z: googlecompute: 'warn: false' to this command task or set 'command_warnings=False' in
2019-11-27T18:41:11Z: googlecompute: ansible.cfg to get rid of this message.
2019-11-27T18:41:11Z: googlecompute:
2019-11-27T18:41:11Z: googlecompute: changed: [default] => {
2019-11-27T18:41:11Z: googlecompute: "changed": true,
2019-11-27T18:41:11Z: googlecompute: "cmd": [
2019-11-27T18:41:11Z: googlecompute: "touch",
2019-11-27T18:41:11Z: googlecompute: "/home/johndoe/example.txt"
2019-11-27T18:41:11Z: googlecompute: ],
2019-11-27T18:41:11Z: googlecompute: "delta": "0:00:00.012942",
2019-11-27T18:41:11Z: googlecompute: "end": "2019-11-27 18:41:11.062358",
2019-11-27T18:41:11Z: googlecompute: "invocation": {
2019-11-27T18:41:11Z: googlecompute: "module_args": {
2019-11-27T18:41:11Z: googlecompute: "_raw_params": "touch \"/home/johndoe/example.txt\"",
2019-11-27T18:41:11Z: googlecompute: "_uses_shell": false,
2019-11-27T18:41:11Z: googlecompute: "argv": null,
2019-11-27T18:41:11Z: googlecompute: "chdir": null,
2019-11-27T18:41:11Z: googlecompute: "creates": null,
2019-11-27T18:41:11Z: googlecompute: "executable": null,
2019-11-27T18:41:11Z: googlecompute: "removes": null,
2019-11-27T18:41:11Z: googlecompute: "stdin": null,
2019-11-27T18:41:11Z: googlecompute: "stdin_add_newline": true,
2019-11-27T18:41:11Z: googlecompute: "strip_empty_ends": true,
2019-11-27T18:41:11Z: googlecompute: "warn": true
2019-11-27T18:41:11Z: googlecompute: }
2019-11-27T18:41:11Z: googlecompute: },
2019-11-27T18:41:11Z: googlecompute: "rc": 0,
2019-11-27T18:41:11Z: googlecompute: "start": "2019-11-27 18:41:11.049416",
2019-11-27T18:41:11Z: googlecompute: "stderr": "",
2019-11-27T18:41:11Z: googlecompute: "stderr_lines": [],
2019-11-27T18:41:11Z: googlecompute: "stdout": "",
2019-11-27T18:41:11Z: googlecompute: "stdout_lines": []
2019-11-27T18:41:11Z: googlecompute: }
2019-11-27T18:41:11Z: googlecompute: META: ran handlers
2019-11-27T18:41:11Z: googlecompute: META: ran handlers
2019-11-27T18:41:11Z: googlecompute:
2019-11-27T18:41:11Z: googlecompute: PLAY RECAP *********************************************************************
2019-11-27T18:41:11Z: googlecompute: default : ok=3 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0
2019-11-27T18:41:11Z: googlecompute:
2019/11/27 18:41:11 [INFO] (telemetry) ending ansible
2019/11/27 18:41:11 packer: 2019/11/27 18:41:11 shutting down the SSH proxy
2019-11-27T18:41:11Z: ==> googlecompute: Deleting instance...
2019/11/27 18:41:13 packer: 2019/11/27 18:41:13 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:15 packer: 2019/11/27 18:41:15 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:18 packer: 2019/11/27 18:41:18 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:20 packer: 2019/11/27 18:41:20 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:22 packer: 2019/11/27 18:41:22 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:25 packer: 2019/11/27 18:41:25 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:27 packer: 2019/11/27 18:41:27 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:29 packer: 2019/11/27 18:41:29 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:31 packer: 2019/11/27 18:41:31 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:34 packer: 2019/11/27 18:41:34 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:36 packer: 2019/11/27 18:41:36 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:38 packer: 2019/11/27 18:41:38 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:41 packer: 2019/11/27 18:41:41 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:43 packer: 2019/11/27 18:41:43 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:45 packer: 2019/11/27 18:41:45 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:47 packer: 2019/11/27 18:41:47 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:50 packer: 2019/11/27 18:41:50 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:52 packer: 2019/11/27 18:41:52 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:54 packer: 2019/11/27 18:41:54 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:57 packer: 2019/11/27 18:41:57 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:41:59 packer: 2019/11/27 18:41:59 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:01 packer: 2019/11/27 18:42:01 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:03 packer: 2019/11/27 18:42:03 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:06 packer: 2019/11/27 18:42:06 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:08 packer: 2019/11/27 18:42:08 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:10 packer: 2019/11/27 18:42:10 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:13 packer: 2019/11/27 18:42:13 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:15 packer: 2019/11/27 18:42:15 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:17 packer: 2019/11/27 18:42:17 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:19 packer: 2019/11/27 18:42:19 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:22 packer: 2019/11/27 18:42:22 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:24 packer: 2019/11/27 18:42:24 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:26 packer: 2019/11/27 18:42:26 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:28 packer: 2019/11/27 18:42:28 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:31 packer: 2019/11/27 18:42:31 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:33 packer: 2019/11/27 18:42:33 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:35 packer: 2019/11/27 18:42:35 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:37 packer: 2019/11/27 18:42:37 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:40 packer: 2019/11/27 18:42:40 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:42 packer: 2019/11/27 18:42:42 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:44 packer: 2019/11/27 18:42:44 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:47 packer: 2019/11/27 18:42:47 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:49 packer: 2019/11/27 18:42:49 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:51 packer: 2019/11/27 18:42:51 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:53 packer: 2019/11/27 18:42:53 Retryable error: retrying for state DONE, got RUNNING
2019-11-27T18:42:56Z: googlecompute: Instance has been deleted!
2019-11-27T18:42:56Z: ==> googlecompute: Creating image...
2019/11/27 18:42:57 packer: 2019/11/27 18:42:57 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:42:59 packer: 2019/11/27 18:42:59 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:01 packer: 2019/11/27 18:43:01 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:04 packer: 2019/11/27 18:43:04 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:06 packer: 2019/11/27 18:43:06 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:09 packer: 2019/11/27 18:43:09 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:11 packer: 2019/11/27 18:43:11 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:13 packer: 2019/11/27 18:43:13 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:16 packer: 2019/11/27 18:43:16 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:18 packer: 2019/11/27 18:43:18 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:20 packer: 2019/11/27 18:43:20 Retryable error: retrying for state DONE, got RUNNING
2019/11/27 18:43:22 packer: 2019/11/27 18:43:22 Retryable error: retrying for state DONE, got RUNNING
2019-11-27T18:43:25Z: ==> googlecompute: Deleting disk...
2019/11/27 18:43:26 packer: 2019/11/27 18:43:26 Retryable error: retrying for state DONE, got RUNNING
2019-11-27T18:43:28Z: googlecompute: Disk has been deleted!
2019-11-27T18:43:28Z: Build 'googlecompute' finished.
==> Builds finished. The artifacts of successful builds are:
2019/11/27 18:43:28 [INFO] (telemetry) ending googlecompute
==> Builds finished. The artifacts of successful builds are:
2019/11/27 18:43:28 machine readable: googlecompute,artifact-count []string{"1"}
2019/11/27 18:43:28 machine readable: googlecompute,artifact []string{"0", "builder-id", "packer.googlecompute"}
2019/11/27 18:43:28 machine readable: googlecompute,artifact []string{"0", "id", "packer-1574880009"}
2019/11/27 18:43:28 machine readable: googlecompute,artifact []string{"0", "string", "A disk image was created: packer-1574880009"}
--> googlecompute: A disk image was created: packer-1574880009
2019/11/27 18:43:28 machine readable: googlecompute,artifact []string{"0", "files-count", "0"}
2019/11/27 18:43:28 machine readable: googlecompute,artifact []string{"0", "end"}
2019/11/27 18:43:28 [INFO] (telemetry) Finalizing.
2019/11/27 18:43:29 waiting for all plugin processes to complete...
2019/11/27 18:43:29 /home/ansible/go/bin/packer: plugin process exited
2019/11/27 18:43:29 /home/ansible/go/bin/packer: plugin process exited
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment