a3tvbgZW

12018/01/25 11:41:44 [INFO] Packer version: 1.1.3
22018/01/25 11:41:44 Packer Target OS/Arch: darwin amd64
32018/01/25 11:41:44 Built with Go Version: go1.9.2
42018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
52018/01/25 11:41:44 Using internal plugin for digitalocean
62018/01/25 11:41:44 Using internal plugin for file
72018/01/25 11:41:44 Using internal plugin for lxd
82018/01/25 11:41:44 Using internal plugin for null
92018/01/25 11:41:44 Using internal plugin for triton
102018/01/25 11:41:44 Using internal plugin for vmware-vmx
112018/01/25 11:41:44 Using internal plugin for amazon-instance
122018/01/25 11:41:44 Using internal plugin for docker
132018/01/25 11:41:44 Using internal plugin for lxc
142018/01/25 11:41:44 Using internal plugin for parallels-iso
152018/01/25 11:41:44 Using internal plugin for vmware-iso
162018/01/25 11:41:44 Using internal plugin for amazon-ebsvolume
172018/01/25 11:41:44 Using internal plugin for cloudstack
182018/01/25 11:41:44 Using internal plugin for hyperv-vmcx
192018/01/25 11:41:44 Using internal plugin for profitbricks
202018/01/25 11:41:44 Using internal plugin for googlecompute
212018/01/25 11:41:44 Using internal plugin for amazon-chroot
222018/01/25 11:41:44 Using internal plugin for amazon-ebs
232018/01/25 11:41:44 Using internal plugin for qemu
242018/01/25 11:41:44 Using internal plugin for azure-arm
252018/01/25 11:41:44 Using internal plugin for openstack
262018/01/25 11:41:44 Using internal plugin for virtualbox-iso
272018/01/25 11:41:44 Using internal plugin for alicloud-ecs
282018/01/25 11:41:44 Using internal plugin for oneandone
292018/01/25 11:41:44 Using internal plugin for parallels-pvm
302018/01/25 11:41:44 Using internal plugin for amazon-ebssurrogate
312018/01/25 11:41:44 Using internal plugin for hyperv-iso
322018/01/25 11:41:44 Using internal plugin for oracle-oci
332018/01/25 11:41:44 Using internal plugin for virtualbox-ovf
342018/01/25 11:41:44 Using internal plugin for windows-restart
352018/01/25 11:41:44 Using internal plugin for chef-client
362018/01/25 11:41:44 Using internal plugin for powershell
372018/01/25 11:41:44 Using internal plugin for puppet-server
382018/01/25 11:41:44 Using internal plugin for puppet-masterless
392018/01/25 11:41:44 Using internal plugin for salt-masterless
402018/01/25 11:41:44 Using internal plugin for shell
412018/01/25 11:41:44 Using internal plugin for windows-shell
422018/01/25 11:41:44 Using internal plugin for chef-solo
432018/01/25 11:41:44 Using internal plugin for converge
442018/01/25 11:41:44 Using internal plugin for file
452018/01/25 11:41:44 Using internal plugin for ansible
462018/01/25 11:41:44 Using internal plugin for ansible-local
472018/01/25 11:41:44 Using internal plugin for shell-local
482018/01/25 11:41:44 Using internal plugin for alicloud-import
492018/01/25 11:41:44 Using internal plugin for docker-push
502018/01/25 11:41:44 Using internal plugin for docker-save
512018/01/25 11:41:44 Using internal plugin for vagrant-cloud
522018/01/25 11:41:44 Using internal plugin for vsphere-template
532018/01/25 11:41:44 Using internal plugin for amazon-import
542018/01/25 11:41:44 Using internal plugin for compress
552018/01/25 11:41:44 Using internal plugin for googlecompute-export
562018/01/25 11:41:44 Using internal plugin for manifest
572018/01/25 11:41:44 Using internal plugin for vsphere
582018/01/25 11:41:44 Using internal plugin for artifice
592018/01/25 11:41:44 Using internal plugin for checksum
602018/01/25 11:41:44 Using internal plugin for docker-tag
612018/01/25 11:41:44 Using internal plugin for vagrant
622018/01/25 11:41:44 Using internal plugin for atlas
632018/01/25 11:41:44 Using internal plugin for docker-import
642018/01/25 11:41:44 Using internal plugin for shell-local
652018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
662018/01/25 11:41:44 Attempting to open config file: /Users/ksmets/.packerconfig
672018/01/25 11:41:44 [WARN] Config file doesn't exist: /Users/ksmets/.packerconfig
682018/01/25 11:41:44 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[null:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-null cloudstack:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-cloudstack alicloud-ecs:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-alicloud-ecs digitalocean:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-digitalocean profitbricks:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-profitbricks file:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-file vmware-iso:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-iso qemu:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-qemu lxc:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxc parallels-iso:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-iso hyperv-vmcx:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-vmcx amazon-chroot:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-chroot hyperv-iso:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-iso docker:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-docker googlecompute:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-googlecompute amazon-ebs:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebs oneandone:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oneandone parallels-pvm:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-pvm amazon-ebssurrogate:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebssurrogate vmware-vmx:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-vmx virtualbox-iso:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-iso oracle-oci:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-oci lxd:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxd triton:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-triton amazon-instance:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-instance amazon-ebsvolume:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebsvolume azure-arm:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-arm openstack:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-openstack virtualbox-ovf:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-ovf] PostProcessors:map[docker-tag:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-tag amazon-import:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-amazon-import artifice:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-artifice googlecompute-export:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-export vsphere:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere checksum:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-checksum vagrant:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant atlas:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-atlas docker-import:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-import alicloud-import:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-alicloud-import vsphere-template:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere-template shell-local:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-shell-local docker-save:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-save compress:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-compress manifest:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-manifest docker-push:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-push vagrant-cloud:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant-cloud] Provisioners:map[windows-restart:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-restart chef-client:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-client powershell:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-powershell chef-solo:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-solo ansible:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible salt-masterless:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-salt-masterless shell:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell file:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-file shell-local:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell-local puppet-server:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-server puppet-masterless:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-masterless windows-shell:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-shell ansible-local:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible-local converge:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-converge]}
692018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
702018/01/25 11:41:44 Setting cache directory: /Users/ksmets/repositories/my-packer-templates/example-ansible-extra-vars/packer_cache
712018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
722018/01/25 11:41:44 Loading builder: amazon-ebs
732018/01/25 11:41:44 Plugin could not be found. Checking same directory as executable.
742018/01/25 11:41:44 Current exe path: /usr/local/bin/packer
752018/01/25 11:41:44 Creating plugin client for path: /usr/local/bin/packer
762018/01/25 11:41:44 Starting plugin: /usr/local/bin/packer []string{"/usr/local/bin/packer", "plugin", "packer-builder-amazon-ebs"}
772018/01/25 11:41:44 Waiting for RPC address for: /usr/local/bin/packer
782018/01/25 11:41:44 packer: 2018/01/25 11:41:44 [INFO] Packer version: 1.1.3
792018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Packer Target OS/Arch: darwin amd64
802018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Built with Go Version: go1.9.2
812018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
822018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Attempting to open config file: /Users/ksmets/.packerconfig
832018/01/25 11:41:44 packer: 2018/01/25 11:41:44 [WARN] Config file doesn't exist: /Users/ksmets/.packerconfig
842018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
852018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
862018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Setting cache directory: /Users/ksmets/repositories/my-packer-templates/example-ansible-extra-vars/packer_cache
872018/01/25 11:41:44 packer: 2018/01/25 11:41:44 args: []string{"packer-builder-amazon-ebs"}
882018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
892018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Plugin minimum port: 10000
902018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Plugin maximum port: 25000
912018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Plugin address: unix /var/folders/pq/yx7lh9vd5hsdp37b84y0rnrm0000gn/T/packer-plugin243515197
922018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Waiting for connection...
932018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Serving a plugin connection...
942018/01/25 11:41:44 Loading provisioner: ansible
952018/01/25 11:41:44 Plugin could not be found. Checking same directory as executable.
962018/01/25 11:41:44 Current exe path: /usr/local/bin/packer
972018/01/25 11:41:44 Creating plugin client for path: /usr/local/bin/packer
982018/01/25 11:41:44 Starting plugin: /usr/local/bin/packer []string{"/usr/local/bin/packer", "plugin", "packer-provisioner-ansible"}
992018/01/25 11:41:44 Waiting for RPC address for: /usr/local/bin/packer
1002018/01/25 11:41:44 packer: 2018/01/25 11:41:44 [INFO] Packer version: 1.1.3
1012018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Packer Target OS/Arch: darwin amd64
1022018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Built with Go Version: go1.9.2
1032018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
1042018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Attempting to open config file: /Users/ksmets/.packerconfig
1052018/01/25 11:41:44 packer: 2018/01/25 11:41:44 [WARN] Config file doesn't exist: /Users/ksmets/.packerconfig
1062018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
1072018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
1082018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Setting cache directory: /Users/ksmets/repositories/my-packer-templates/example-ansible-extra-vars/packer_cache
1092018/01/25 11:41:44 packer: 2018/01/25 11:41:44 args: []string{"packer-provisioner-ansible"}
1102018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Detected home directory from env var: /Users/ksmets
1112018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Plugin minimum port: 10000
1122018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Plugin maximum port: 25000
1132018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Plugin address: unix /var/folders/pq/yx7lh9vd5hsdp37b84y0rnrm0000gn/T/packer-plugin514630873
1142018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Waiting for connection...
1152018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Serving a plugin connection...
1162018/01/25 11:41:44 ui: amazon-ebs output will be in this color.
1172018/01/25 11:41:44 ui: 
1182018/01/25 11:41:44 Build debug mode: false
1192018/01/25 11:41:44 Force build: false
1202018/01/25 11:41:44 On error: 
1212018/01/25 11:41:44 Preparing build: amazon-ebs
1222018/01/25 11:41:44 packer: 2018/01/25 11:41:44 Config: {PackerConfig:{PackerBuildName:amazon-ebs PackerBuilderType:amazon-ebs PackerDebug:false PackerForce:false PackerOnError: PackerUserVars:map[aws_instance_type:t2.micro aws_source_ami_owner:099720109477 aws_source_ami_ubuntu_version:xenial-16.04 aws_region:eu-west-1]} AccessConfig:{AccessKey: CustomEndpointEc2: MFACode: ProfileName: RawRegion:eu-west-1 SecretKey: SkipValidation:false Token: session:<nil>} AMIConfig:{AMIName:packer-example-20180125104144 AMIDescription: AMIVirtType: AMIUsers:[] AMIGroups:[] AMIProductCodes:[] AMIRegions:[] AMISkipRegionValidation:false AMITags:map[] AMIENASupport:false AMISriovNetSupport:false AMIForceDeregister:false AMIForceDeleteSnapshot:false AMIEncryptBootVolume:false AMIKmsKeyId: AMIRegionKMSKeyIDs:map[] SnapshotTags:map[] SnapshotUsers:[] SnapshotGroups:[]} BlockDevices:{AMIBlockDevices:{AMIMappings:[]} LaunchBlockDevices:{LaunchMappings:[]}} RunConfig:{AssociatePublicIpAddress:false AvailabilityZone: EbsOptimized:false IamInstanceProfile: InstanceType:t2.micro RunTags:map[] SourceAmi: SourceAmiFilter:{Filters:map[0xc4204445f0:0xc420444610 0xc420444630:0xc420444650 0xc420444670:0xc420444690] Owners:[0xc420444710] MostRecent:true} SpotPrice: SpotPriceAutoProduct: DisableStopInstance:false SecurityGroupId: SecurityGroupIds:[] TemporarySGSourceCidr:0.0.0.0/0 SubnetId: TemporaryKeyPairName:packer_5a69b468-ddc1-2738-c604-d10f5f5631f4 UserData: UserDataFile: WindowsPasswordTimeout:20m0s VpcId: InstanceInitiatedShutdownBehavior:stop Comm:{Type:ssh SSHHost: SSHPort:22 SSHUsername:ubuntu SSHPassword: SSHPrivateKey: SSHPty:false SSHTimeout:5m0s SSHAgentAuth:false SSHDisableAgentForwarding:false SSHHandshakeAttempts:10 SSHBastionHost: SSHBastionPort:0 SSHBastionAgentAuth:false SSHBastionUsername: SSHBastionPassword: SSHBastionPrivateKey: SSHFileTransferMethod:scp SSHProxyHost: SSHProxyPort:0 SSHProxyUsername: SSHProxyPassword: WinRMUser: WinRMPassword: WinRMHost: WinRMPort:0 WinRMTimeout:0s WinRMUseSSL:false WinRMInsecure:false WinRMUseNTLM:false WinRMTransportDecorator:<nil>} SSHKeyPairName: SSHPrivateIp:false SSHInterface:} VolumeRunTags:map[] ctx:{Data:<nil> Funcs:map[clean_ami_name:0x186cb50] UserVariables:map[aws_instance_type:t2.micro aws_source_ami_owner:099720109477 aws_source_ami_ubuntu_version:xenial-16.04 aws_region:eu-west-1] EnableEnv:false BuildName:amazon-ebs BuildType:amazon-ebs TemplatePath:/Users/ksmets/repositories/my-packer-templates/example-ansible-extra-vars/template-json.json}}
1232018/01/25 11:41:46 packer: 2018/01/25 11:41:46 ansible-playbook version: 2.4.2.0
1242018/01/25 11:41:46 Waiting on builds to complete...
1252018/01/25 11:41:46 Starting build run: amazon-ebs
1262018/01/25 11:41:46 Running builder: amazon-ebs
1272018/01/25 11:41:46 [INFO] (telemetry) Starting builder amazon-ebs
1282018/01/25 11:41:46 packer: 2018/01/25 11:41:46 Found region eu-west-1
1292018/01/25 11:41:46 ui: ==> amazon-ebs: Prevalidating AMI Name: packer-example-20180125104144
1302018/01/25 11:41:48 packer: 2018/01/25 11:41:48 Using AMI Filters {
1312018/01/25 11:41:48 packer:   Filters: [{
1322018/01/25 11:41:48 packer:       Name: "virtualization-type",
1332018/01/25 11:41:48 packer:       Values: ["hvm"]
1342018/01/25 11:41:48 packer:     },{
1352018/01/25 11:41:48 packer:       Name: "name",
1362018/01/25 11:41:48 packer:       Values: ["ubuntu/images/*ubuntu-xenial-16.04-amd64-server-*"]
1372018/01/25 11:41:48 packer:     },{
1382018/01/25 11:41:48 packer:       Name: "root-device-type",
1392018/01/25 11:41:48 packer:       Values: ["ebs"]
1402018/01/25 11:41:48 packer:     }],
1412018/01/25 11:41:48 packer:   Owners: ["099720109477"]
1422018/01/25 11:41:48 packer: }
1432018/01/25 11:41:49 ui:     amazon-ebs: Found Image ID: ami-7a187c03
1442018/01/25 11:41:49 ui: ==> amazon-ebs: Creating temporary keypair: packer_5a69b468-ddc1-2738-c604-d10f5f5631f4
1452018/01/25 11:41:50 ui: ==> amazon-ebs: Creating temporary security group for this instance: packer_5a69b46e-4bf2-e823-4ab3-6a1be3a56f5e
1462018/01/25 11:41:50 ui: ==> amazon-ebs: Authorizing access to port 22 from 0.0.0.0/0 in the temporary security group...
1472018/01/25 11:41:50 packer: 2018/01/25 11:41:50 [DEBUG] Waiting for temporary security group: sg-3ebf4944
1482018/01/25 11:41:50 packer: 2018/01/25 11:41:50 [DEBUG] Found security group sg-3ebf4944
1492018/01/25 11:41:50 ui: ==> amazon-ebs: Launching a source AWS instance...
1502018/01/25 11:41:50 ui: ==> amazon-ebs: Adding tags to source instance
1512018/01/25 11:41:50 ui:     amazon-ebs: Adding tag: "Name": "Packer Builder"
1522018/01/25 11:41:51 ui:     amazon-ebs: Instance ID: i-0c83ecad713ba6f7d
1532018/01/25 11:41:51 ui: ==> amazon-ebs: Waiting for instance (i-0c83ecad713ba6f7d) to become ready...
1542018/01/25 11:44:54 packer: 2018/01/25 11:44:54 [INFO] Not using winrm communicator, skipping get password...
1552018/01/25 11:44:54 packer: 2018/01/25 11:44:54 [INFO] Waiting for SSH, up to timeout: 5m0s
1562018/01/25 11:44:54 ui: ==> amazon-ebs: Waiting for SSH to become available...
1572018/01/25 11:44:54 packer: 2018/01/25 11:44:54 [INFO] Attempting SSH connection...
1582018/01/25 11:44:54 packer: 2018/01/25 11:44:54 reconnecting to TCP connection for SSH
1592018/01/25 11:44:54 packer: 2018/01/25 11:44:54 handshaking with SSH
1602018/01/25 11:44:54 packer: 2018/01/25 11:44:54 handshake complete!
1612018/01/25 11:44:54 packer: 2018/01/25 11:44:54 opening new ssh session
1622018/01/25 11:44:54 packer: 2018/01/25 11:44:54 [INFO] agent forwarding enabled
1632018/01/25 11:44:54 ui: ==> amazon-ebs: Connected to SSH!
1642018/01/25 11:44:54 packer: 2018/01/25 11:44:54 Running the provision hook
1652018/01/25 11:44:54 [INFO] (telemetry) Starting provisioner ansible
1662018/01/25 11:44:54 ui: ==> amazon-ebs: Provisioning with Ansible...
1672018/01/25 11:44:55 packer: 2018/01/25 11:44:55 SSH proxy: serving on 127.0.0.1:58416
1682018/01/25 11:44:55 ui: ==> amazon-ebs: Executing Ansible: ansible-playbook --extra-vars packer_build_name=amazon-ebs packer_builder_type=amazon-ebs -i /var/folders/pq/yx7lh9vd5hsdp37b84y0rnrm0000gn/T/packer-provisioner-ansible656372339 /Users/ksmets/repositories/my-packer-templates/example-ansible-extra-vars/playbook.yml --private-key /var/folders/pq/yx7lh9vd5hsdp37b84y0rnrm0000gn/T/ansible-key129001060 --extra-vars '{"ansible_python_interpreter": "/usr/bin/env python3"}'
1692018/01/25 11:44:56 ui:     amazon-ebs:
1702018/01/25 11:44:56 ui:     amazon-ebs: PLAY [all] *********************************************************************
1712018/01/25 11:44:56 ui:     amazon-ebs:
1722018/01/25 11:44:56 ui:     amazon-ebs: TASK [Gathering Facts] *********************************************************
1732018/01/25 11:44:57 packer: 2018/01/25 11:44:57 SSH proxy: accepted connection
1742018/01/25 11:44:57 packer: 2018/01/25 11:44:57 authentication attempt from 127.0.0.1:58419 to 127.0.0.1:58416 as ksmets using none
1752018/01/25 11:44:57 packer: 2018/01/25 11:44:57 authentication attempt from 127.0.0.1:58419 to 127.0.0.1:58416 as ksmets using publickey
1762018/01/25 11:44:57 packer: 2018/01/25 11:44:57 authentication attempt from 127.0.0.1:58419 to 127.0.0.1:58416 as ksmets using publickey
1772018/01/25 11:44:57 packer: 2018/01/25 11:44:57 authentication attempt from 127.0.0.1:58419 to 127.0.0.1:58416 as ksmets using publickey
1782018/01/25 11:44:57 packer: 2018/01/25 11:44:57 rejecting auth-agent-req@openssh.com request
1792018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new env request: LC_CTYPE=en_US.UTF-8
1802018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new exec request: /bin/sh -c 'echo ~ && sleep 0'
1812018/01/25 11:44:57 packer: 2018/01/25 11:44:57 opening new ssh session
1822018/01/25 11:44:57 packer: 2018/01/25 11:44:57 starting remote command: /bin/sh -c 'echo ~ && sleep 0'
1832018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] RPC endpoint: Communicator ended with: 0
1842018/01/25 11:44:57 [INFO] 13 bytes written for 'stdout'
1852018/01/25 11:44:57 [INFO] 0 bytes written for 'stderr'
1862018/01/25 11:44:57 [INFO] RPC client: Communicator ended with: 0
1872018/01/25 11:44:57 [INFO] RPC endpoint: Communicator ended with: 0
1882018/01/25 11:44:57 [INFO] 0 bytes written for 'stdin'
1892018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stderr'
1902018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 13 bytes written for 'stdout'
1912018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] RPC client: Communicator ended with: 0
1922018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stdin'
1932018/01/25 11:44:57 packer: 2018/01/25 11:44:57 rejecting auth-agent-req@openssh.com request
1942018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new env request: LC_CTYPE=en_US.UTF-8
1952018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new exec request: /bin/sh -c '( umask 77 && mkdir -p "` echo /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513 `" && echo ansible-tmp-1516877097.04-207189055174513="` echo /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513 `" ) && sleep 0'
1962018/01/25 11:44:57 packer: 2018/01/25 11:44:57 opening new ssh session
1972018/01/25 11:44:57 packer: 2018/01/25 11:44:57 starting remote command: /bin/sh -c '( umask 77 && mkdir -p "` echo /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513 `" && echo ansible-tmp-1516877097.04-207189055174513="` echo /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513 `" ) && sleep 0'
1982018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] RPC endpoint: Communicator ended with: 0
1992018/01/25 11:44:57 [INFO] 110 bytes written for 'stdout'
2002018/01/25 11:44:57 [INFO] 0 bytes written for 'stderr'
2012018/01/25 11:44:57 [INFO] RPC client: Communicator ended with: 0
2022018/01/25 11:44:57 [INFO] RPC endpoint: Communicator ended with: 0
2032018/01/25 11:44:57 [INFO] 0 bytes written for 'stdin'
2042018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 110 bytes written for 'stdout'
2052018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stderr'
2062018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] RPC client: Communicator ended with: 0
2072018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stdin'
2082018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new env request: LC_CTYPE=en_US.UTF-8
2092018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new exec request: scp -t /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/setup.py
2102018/01/25 11:44:57 packer: 2018/01/25 11:44:57 opening new ssh session
2112018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 196418 bytes written for 'uploadData'
2122018/01/25 11:44:57 [INFO] 196418 bytes written for 'uploadData'
2132018/01/25 11:44:57 packer: 2018/01/25 11:44:57 Starting remote scp process:  scp -vt /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513
2142018/01/25 11:44:57 packer: 2018/01/25 11:44:57 Started SCP session, beginning transfers...
2152018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [DEBUG] scp: Uploading setup.py: perms=C0600 size=196418
2162018/01/25 11:44:57 packer: 2018/01/25 11:44:57 SCP session complete, closing stdin pipe.
2172018/01/25 11:44:57 packer: 2018/01/25 11:44:57 Waiting for SSH session to complete.
2182018/01/25 11:44:57 packer: 2018/01/25 11:44:57 scp stderr (length 28): Sink: C0600 196418 setup.py
2192018/01/25 11:44:57 packer: 2018/01/25 11:44:57 rejecting auth-agent-req@openssh.com request
2202018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new env request: LC_CTYPE=en_US.UTF-8
2212018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new exec request: /bin/sh -c 'chmod u+x /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/ /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/setup.py && sleep 0'
2222018/01/25 11:44:57 packer: 2018/01/25 11:44:57 opening new ssh session
2232018/01/25 11:44:57 packer: 2018/01/25 11:44:57 starting remote command: /bin/sh -c 'chmod u+x /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/ /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/setup.py && sleep 0'
2242018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] RPC endpoint: Communicator ended with: 0
2252018/01/25 11:44:57 [INFO] 0 bytes written for 'stdout'
2262018/01/25 11:44:57 [INFO] 0 bytes written for 'stderr'
2272018/01/25 11:44:57 [INFO] RPC client: Communicator ended with: 0
2282018/01/25 11:44:57 [INFO] RPC endpoint: Communicator ended with: 0
2292018/01/25 11:44:57 [INFO] 0 bytes written for 'stdin'
2302018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stdout'
2312018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stderr'
2322018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] RPC client: Communicator ended with: 0
2332018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stdin'
2342018/01/25 11:44:57 packer: 2018/01/25 11:44:57 rejecting auth-agent-req@openssh.com request
2352018/01/25 11:44:57 packer: 2018/01/25 11:44:57 ansible provisioner pty-req request
2362018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new env request: LC_CTYPE=en_US.UTF-8
2372018/01/25 11:44:57 packer: 2018/01/25 11:44:57 new exec request: /bin/sh -c '/usr/bin/python /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/setup.py; rm -rf "/home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/" > /dev/null 2>&1 && sleep 0'
2382018/01/25 11:44:57 packer: 2018/01/25 11:44:57 opening new ssh session
2392018/01/25 11:44:57 packer: 2018/01/25 11:44:57 starting remote command: /bin/sh -c '/usr/bin/python /home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/setup.py; rm -rf "/home/ubuntu/.ansible/tmp/ansible-tmp-1516877097.04-207189055174513/" > /dev/null 2>&1 && sleep 0'
2402018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] RPC endpoint: Communicator ended with: 0
2412018/01/25 11:44:57 [INFO] 39 bytes written for 'stderr'
2422018/01/25 11:44:57 [INFO] 0 bytes written for 'stdout'
2432018/01/25 11:44:57 [INFO] RPC client: Communicator ended with: 0
2442018/01/25 11:44:57 [INFO] RPC endpoint: Communicator ended with: 0
2452018/01/25 11:44:57 [INFO] 0 bytes written for 'stdin'
2462018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stdout'
2472018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 39 bytes written for 'stderr'
2482018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] RPC client: Communicator ended with: 0
2492018/01/25 11:44:57 packer: 2018/01/25 11:44:57 [INFO] 0 bytes written for 'stdin'
2502018/01/25 11:44:57 ui:     amazon-ebs: fatal: [default]: FAILED! => {"changed": false, "module_stderr": "/bin/sh: 1: /usr/bin/python: not found\nShared connection to 127.0.0.1 closed.\r\n", "module_stdout": "", "msg": "MODULE FAILURE", "rc": 0}
2512018/01/25 11:44:57 ui:     amazon-ebs: 	to retry, use: --limit @/Users/ksmets/repositories/my-packer-templates/example-ansible-extra-vars/playbook.retry
2522018/01/25 11:44:57 ui:     amazon-ebs:
2532018/01/25 11:44:57 ui:     amazon-ebs: PLAY RECAP *********************************************************************
2542018/01/25 11:44:57 ui:     amazon-ebs: default                    : ok=0    changed=0    unreachable=0    failed=1
2552018/01/25 11:44:57 ui:     amazon-ebs:
2562018/01/25 11:44:57 packer: 2018/01/25 11:44:57 shutting down the SSH proxy
2572018/01/25 11:44:57 [INFO] (telemetry) ending ansible
2582018/01/25 11:44:57 [INFO] (telemetry) found error: Error executing Ansible: Non-zero exit status: exit status 2
2592018/01/25 11:44:57 ui: ==> amazon-ebs: Terminating the source AWS instance...
2602018/01/25 11:44:57 packer: 2018/01/25 11:44:57 Waiting for state to become: terminated
2612018/01/25 11:44:57 packer: 2018/01/25 11:44:57 Using 2s as polling delay (change with AWS_POLL_DELAY_SECONDS)
2622018/01/25 11:44:57 packer: 2018/01/25 11:44:57 Allowing 300s to complete (change with AWS_TIMEOUT_SECONDS)
2632018/01/25 11:45:46 ui: ==> amazon-ebs: Cleaning up any extra volumes...
2642018/01/25 11:45:46 ui: ==> amazon-ebs: No volumes to clean up, skipping
2652018/01/25 11:45:46 ui: ==> amazon-ebs: Deleting temporary security group...
2662018/01/25 11:45:46 ui: ==> amazon-ebs: Deleting temporary keypair...
2672018/01/25 11:45:46 [INFO] (telemetry) ending amazon-ebs
2682018/01/25 11:45:46 [INFO] (telemetry) found error: Error executing Ansible: Non-zero exit status: exit status 2
2692018/01/25 11:45:46 ui error: Build 'amazon-ebs' errored: Error executing Ansible: Non-zero exit status: exit status 2
2702018/01/25 11:45:46 Builds completed. Waiting on interrupt barrier...
2712018/01/25 11:45:46 machine readable: error-count []string{"1"}
2722018/01/25 11:45:46 ui error: 
273==> Some builds didn't complete successfully and had errors:
2742018/01/25 11:45:46 machine readable: amazon-ebs,error []string{"Error executing Ansible: Non-zero exit status: exit status 2"}
2752018/01/25 11:45:46 ui error: --> amazon-ebs: Error executing Ansible: Non-zero exit status: exit status 2
2762018/01/25 11:45:46 ui: 
277==> Builds finished but no artifacts were created.
2782018/01/25 11:45:46 [INFO] (telemetry) Finalizing.
2792018/01/25 11:45:47 waiting for all plugin processes to complete...
2802018/01/25 11:45:47 /usr/local/bin/packer: plugin process exited
2812018/01/25 11:45:47 /usr/local/bin/packer: plugin process exited