packer debug output

gistfile1.txt

ESC]0;dh3@maybe: ~/alpine-linux-image^Gdh3@maybe:~/alpine-linux-image$ PACKER_LOG=1 packer build template-broken-wait.j ^Mson
2017/12/13 16:13:14 [INFO] Packer version: 1.1.3
2017/12/13 16:13:14 Packer Target OS/Arch: linux amd64
2017/12/13 16:13:14 Built with Go Version: go1.9
2017/12/13 16:13:14 [DEBUG] Discovered plugin: amazon-chroot = /usr/local/bin/packer-builder-amazon-chroot
2017/12/13 16:13:14 [DEBUG] Discovered plugin: amazon-ebs = /usr/local/bin/packer-builder-amazon-ebs
2017/12/13 16:13:14 [DEBUG] Discovered plugin: amazon-instance = /usr/local/bin/packer-builder-amazon-instance
2017/12/13 16:13:14 [DEBUG] Discovered plugin: digitalocean = /usr/local/bin/packer-builder-digitalocean
2017/12/13 16:13:14 [DEBUG] Discovered plugin: docker = /usr/local/bin/packer-builder-docker
2017/12/13 16:13:14 [DEBUG] Discovered plugin: file = /usr/local/bin/packer-builder-file
2017/12/13 16:13:14 [DEBUG] Discovered plugin: googlecompute = /usr/local/bin/packer-builder-googlecompute
2017/12/13 16:13:14 [DEBUG] Discovered plugin: null = /usr/local/bin/packer-builder-null
2017/12/13 16:13:14 [DEBUG] Discovered plugin: openstack = /usr/local/bin/packer-builder-openstack
2017/12/13 16:13:14 [DEBUG] Discovered plugin: parallels-iso = /usr/local/bin/packer-builder-parallels-iso
2017/12/13 16:13:14 [DEBUG] Discovered plugin: parallels-pvm = /usr/local/bin/packer-builder-parallels-pvm
2017/12/13 16:13:14 [DEBUG] Discovered plugin: qemu = /usr/local/bin/packer-builder-qemu
2017/12/13 16:13:14 [DEBUG] Discovered plugin: virtualbox-iso = /usr/local/bin/packer-builder-virtualbox-iso
2017/12/13 16:13:14 [DEBUG] Discovered plugin: virtualbox-ovf = /usr/local/bin/packer-builder-virtualbox-ovf
2017/12/13 16:13:14 [DEBUG] Discovered plugin: vmware-iso = /usr/local/bin/packer-builder-vmware-iso
2017/12/13 16:13:14 [DEBUG] Discovered plugin: vmware-vmx = /usr/local/bin/packer-builder-vmware-vmx
2017/12/13 16:13:14 [DEBUG] Discovered plugin: artifice = /usr/local/bin/packer-post-processor-artifice
2017/12/13 16:13:14 [DEBUG] Discovered plugin: atlas = /usr/local/bin/packer-post-processor-atlas
2017/12/13 16:13:14 [DEBUG] Discovered plugin: compress = /usr/local/bin/packer-post-processor-compress
2017/12/13 16:13:14 [DEBUG] Discovered plugin: docker-import = /usr/local/bin/packer-post-processor-docker-import
2017/12/13 16:13:14 [DEBUG] Discovered plugin: docker-push = /usr/local/bin/packer-post-processor-docker-push
2017/12/13 16:13:14 [DEBUG] Discovered plugin: docker-save = /usr/local/bin/packer-post-processor-docker-save
2017/12/13 16:13:14 [DEBUG] Discovered plugin: docker-tag = /usr/local/bin/packer-post-processor-docker-tag
2017/12/13 16:13:14 [DEBUG] Discovered plugin: vagrant = /usr/local/bin/packer-post-processor-vagrant
2017/12/13 16:13:14 [DEBUG] Discovered plugin: vagrant-cloud = /usr/local/bin/packer-post-processor-vagrant-cloud
2017/12/13 16:13:14 [DEBUG] Discovered plugin: vsphere = /usr/local/bin/packer-post-processor-vsphere
2017/12/13 16:13:14 [DEBUG] Discovered plugin: ansible-local = /usr/local/bin/packer-provisioner-ansible-local
2017/12/13 16:13:14 [DEBUG] Discovered plugin: chef-client = /usr/local/bin/packer-provisioner-chef-client
2017/12/13 16:13:14 [DEBUG] Discovered plugin: chef-solo = /usr/local/bin/packer-provisioner-chef-solo
2017/12/13 16:13:14 [DEBUG] Discovered plugin: file = /usr/local/bin/packer-provisioner-file
2017/12/13 16:13:14 [DEBUG] Discovered plugin: powershell = /usr/local/bin/packer-provisioner-powershell
2017/12/13 16:13:14 [DEBUG] Discovered plugin: puppet-masterless = /usr/local/bin/packer-provisioner-puppet-masterless
2017/12/13 16:13:14 [DEBUG] Discovered plugin: puppet-server = /usr/local/bin/packer-provisioner-puppet-server
2017/12/13 16:13:14 [DEBUG] Discovered plugin: salt-masterless = /usr/local/bin/packer-provisioner-salt-masterless
2017/12/13 16:13:14 [DEBUG] Discovered plugin: shell = /usr/local/bin/packer-provisioner-shell
2017/12/13 16:13:14 [DEBUG] Discovered plugin: shell-local = /usr/local/bin/packer-provisioner-shell-local
2017/12/13 16:13:14 [DEBUG] Discovered plugin: windows-restart = /usr/local/bin/packer-provisioner-windows-restart
2017/12/13 16:13:14 [DEBUG] Discovered plugin: windows-shell = /usr/local/bin/packer-provisioner-windows-shell
2017/12/13 16:13:14 Detected home directory from env var: /home/dh3
2017/12/13 16:13:14 Using internal plugin for alicloud-ecs
2017/12/13 16:13:14 Using internal plugin for hyperv-iso
2017/12/13 16:13:14 Using internal plugin for oracle-oci
2017/12/13 16:13:14 Using internal plugin for hyperv-vmcx
2017/12/13 16:13:14 Using internal plugin for lxc
2017/12/13 16:13:14 Using internal plugin for azure-arm
2017/12/13 16:13:14 Using internal plugin for amazon-ebsvolume
2017/12/13 16:13:14 Using internal plugin for cloudstack
2017/12/13 16:13:14 Using internal plugin for amazon-ebssurrogate
2017/12/13 16:13:14 Using internal plugin for oneandone
2017/12/13 16:13:14 Using internal plugin for triton
2017/12/13 16:13:14 Using internal plugin for lxd
2017/12/13 16:13:14 Using internal plugin for profitbricks
2017/12/13 16:13:14 Using internal plugin for ansible
2017/12/13 16:13:14 Using internal plugin for converge
2017/12/13 16:13:14 Using internal plugin for alicloud-import
2017/12/13 16:13:14 Using internal plugin for checksum
2017/12/13 16:13:14 Using internal plugin for shell-local
2017/12/13 16:13:14 Using internal plugin for googlecompute-export
2017/12/13 16:13:14 Using internal plugin for manifest
2017/12/13 16:13:14 Using internal plugin for amazon-import
2017/12/13 16:13:14 Using internal plugin for vsphere-template
2017/12/13 16:13:14 Detected home directory from env var: /home/dh3
2017/12/13 16:13:14 Attempting to open config file: /home/dh3/.packerconfig
2017/12/13 16:13:14 [WARN] Config file doesn't exist: /home/dh3/.packerconfig
2017/12/13 16:13:14 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[openstack:/usr/local/bin/packer-builder-openstack lxc:/usr/local/bin/packer-PACKERSPACE-plug
in-PACKERSPACE-packer-builder-lxc azure-arm:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-arm cloudstack:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-cloudstack amazon-ebssurrogate:/usr/lo
cal/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebssurrogate file:/usr/local/bin/packer-builder-file parallels-iso:/usr/local/bin/packer-builder-parallels-iso digitalocean:/usr/local/bin/packer-builder-digitalocean orac
le-oci:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-oci lxd:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxd profitbricks:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-buil
der-profitbricks amazon-chroot:/usr/local/bin/packer-builder-amazon-chroot amazon-instance:/usr/local/bin/packer-builder-amazon-instance googlecompute:/usr/local/bin/packer-builder-googlecompute parallels-pvm:/usr/local/bin/packer-builder-
parallels-pvm oneandone:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oneandone vmware-iso:/usr/local/bin/packer-builder-vmware-iso vmware-vmx:/usr/local/bin/packer-builder-vmware-vmx amazon-ebsvolume:/usr/local/bin/p
acker-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebsvolume triton:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-triton docker:/usr/local/bin/packer-builder-docker qemu:/usr/local/bin/packer-builder-qemu virt
ualbox-iso:/usr/local/bin/packer-builder-virtualbox-iso virtualbox-ovf:/usr/local/bin/packer-builder-virtualbox-ovf amazon-ebs:/usr/local/bin/packer-builder-amazon-ebs null:/usr/local/bin/packer-builder-null alicloud-ecs:/usr/local/bin/pac
ker-PACKERSPACE-plugin-PACKERSPACE-packer-builder-alicloud-ecs hyperv-iso:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-iso hyperv-vmcx:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv
-vmcx] PostProcessors:map[atlas:/usr/local/bin/packer-post-processor-atlas shell-local:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-shell-local compress:/usr/local/bin/packer-post-processor-compress vsphere:/u
sr/local/bin/packer-post-processor-vsphere alicloud-import:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-alicloud-import amazon-import:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-
amazon-import docker-save:/usr/local/bin/packer-post-processor-docker-save docker-tag:/usr/local/bin/packer-post-processor-docker-tag vagrant:/usr/local/bin/packer-post-processor-vagrant checksum:/usr/local/bin/packer-PACKERSPACE-plugin-PA
CKERSPACE-packer-post-processor-checksum manifest:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-manifest vsphere-template:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere-templ
ate artifice:/usr/local/bin/packer-post-processor-artifice docker-import:/usr/local/bin/packer-post-processor-docker-import docker-push:/usr/local/bin/packer-post-processor-docker-push vagrant-cloud:/usr/local/bin/packer-post-processor-vag
rant-cloud googlecompute-export:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-export] Provisioners:map[file:/usr/local/bin/packer-provisioner-file puppet-masterless:/usr/local/bin/packer-provision
er-puppet-masterless shell:/usr/local/bin/packer-provisioner-shell windows-shell:/usr/local/bin/packer-provisioner-windows-shell ansible:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible ansible-local:/usr/loc
al/bin/packer-provisioner-ansible-local chef-client:/usr/local/bin/packer-provisioner-chef-client converge:/usr/local/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-converge powershell:/usr/local/bin/packer-provisioner-powers
hell salt-masterless:/usr/local/bin/packer-provisioner-salt-masterless chef-solo:/usr/local/bin/packer-provisioner-chef-solo windows-restart:/usr/local/bin/packer-provisioner-windows-restart puppet-server:/usr/local/bin/packer-provisioner-
puppet-server shell-local:/usr/local/bin/packer-provisioner-shell-local]}
2017/12/13 16:13:14 Detected home directory from env var: /home/dh3
2017/12/13 16:13:14 Setting cache directory: /home/dh3/alpine-linux-image/packer_cache
2017/12/13 16:13:14 Detected home directory from env var: /home/dh3
2017/12/13 16:13:14 Loading builder: qemu
2017/12/13 16:13:14 Creating plugin client for path: /usr/local/bin/packer-builder-qemu
2017/12/13 16:13:14 Starting plugin: /usr/local/bin/packer-builder-qemu []string{"/usr/local/bin/packer-builder-qemu"}
2017/12/13 16:13:14 Waiting for RPC address for: /usr/local/bin/packer-builder-qemu
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Plugin minimum port: 10000
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Plugin maximum port: 25000
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Plugin address: unix /tmp/packer-plugin575869636
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Waiting for connection...
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Serving a plugin connection...
2017/12/13 16:13:14 ui: ESC[1;32mqemu output will be in this color.ESC[0m
2017/12/13 16:13:14 ui: 
2017/12/13 16:13:14 Build debug mode: false
2017/12/13 16:13:14 Force build: false
2017/12/13 16:13:14 On error: 
2017/12/13 16:13:14 Preparing build: qemu
ESC[1;32mqemu output will be in this color.ESC[0m

2017/12/13 16:13:14 Waiting on builds to complete...
2017/12/13 16:13:14 Starting build run: qemu
2017/12/13 16:13:14 Running builder: qemu
2017/12/13 16:13:14 [INFO] (telemetry) Starting builder qemu
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Qemu path: /usr/bin/qemu-system-x86_64, Qemu Image page: /usr/bin/qemu-img
2017/12/13 16:13:14 ui: ESC[1;32m==> qemu: Downloading or copying ISOESC[0m
ESC[1;32m==> qemu: Downloading or copying ISOESC[0m
2017/12/13 16:13:14 ui: ESC[0;32m    qemu: Downloading or copying: http://dl-cdn.alpinelinux.org/alpine/v3.7/releases/x86_64/alpine-virt-3.7.0-x86_64.isoESC[0m
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Acquiring lock to download: http://dl-cdn.alpinelinux.org/alpine/v3.7/releases/x86_64/alpine-virt-3.7.0-x86_64.iso
ESC[0;32m    qemu: Downloading or copying: http://dl-cdn.alpinelinux.org/alpine/v3.7/releases/x86_64/alpine-virt-3.7.0-x86_64.isoESC[0m
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Verifying checksum of /home/dh3/alpine-linux-image/packer_cache/51d27571ce02061b20bf076fb10be00e69205ce70f1f2ef7579b310c1a1b7dbf.iso
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 [DEBUG] Initial checksum matched, no download needed.
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 No floppy files specified. Floppy disk will not be made.
2017/12/13 16:13:14 ui: ESC[1;32m==> qemu: Creating hard drive...ESC[0m
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Executing qemu-img: []string{"create", "-f", "qcow2", "/tmp/alpine-image/packer-qemu", "1000M"}
ESC[1;32m==> qemu: Creating hard drive...ESC[0m
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 stdout: Formatting '/tmp/alpine-image/packer-qemu', fmt=qcow2 size=1048576000 encryption=off cluster_size=65536 lazy_refcounts=off refcount_bits=16
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 stderr:
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Looking for available SSH port between 2222 and 4444
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Trying port: 3499
2017/12/13 16:13:14 ui: ESC[1;32m==> qemu: Found port for SSH: 3499.ESC[0m
ESC[1;32m==> qemu: Found port for SSH: 3499.ESC[0m
ESC[1;32m==> qemu: Looking for available port between 5900 and 6000ESC[0m
2017/12/13 16:13:14 ui: ESC[1;32m==> qemu: Looking for available port between 5900 and 6000ESC[0m
2017/12/13 16:13:14 ui: ESC[1;32m==> qemu: Found available VNC port: 5987ESC[0m
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Looking for available port between 5900 and 6000
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Trying port: 5987
ESC[1;32m==> qemu: Found available VNC port: 5987ESC[0m
2017/12/13 16:13:14 ui: ESC[1;32m==> qemu: Starting VM, booting from CD-ROMESC[0m
ESC[1;32m==> qemu: Starting VM, booting from CD-ROMESC[0m
2017/12/13 16:13:14 ui: ESC[0;32m    qemu: WARNING: The VM will be started with no hardware acceleration.
    qemu: The installation may take considerably longer to finish.
    qemu:ESC[0m
ESC[0;32m    qemu: WARNING: The VM will be started with no hardware acceleration.
    qemu: The installation may take considerably longer to finish.
    qemu:ESC[0m
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Qemu Builder has no floppy files, not attaching a floppy.
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Executing /usr/bin/qemu-system-x86_64: []string{"-boot", "once=d", "-vnc", "0.0.0.0:87", "-display", "sdl", "-device", "virtio-net,netdev=user.0", "-netdev", "user,id=user.0,hostfwd=tcp::3499-:22", "-drive", "file=/tmp/alpine-image/packer-qemu,if=virtio,cache=writeback,discard=ignore", "-m", "512M", "-name", "packer-qemu", "-machine", "type=pc", "-cdrom", "/home/dh3/alpine-linux-image/packer_cache/51d27571ce02061b20bf076fb10be00e69205ce70f1f2ef7579b310c1a1b7dbf.iso"}
2017/12/13 16:13:14 packer-builder-qemu: 2017/12/13 16:13:14 Started Qemu. Pid: 25061
2017/12/13 16:13:15 packer-builder-qemu: 2017/12/13 16:13:15 Qemu stderr: warning: TCG doesn't support requested feature: CPUID.01H:ECX.vmx [bit 5]
ESC[1;32m==> qemu: Waiting 1m0s for boot...ESC[0m
2017/12/13 16:13:16 ui: ESC[1;32m==> qemu: Waiting 1m0s for boot...ESC[0m
2017/12/13 16:14:16 ui: ESC[1;32m==> qemu: Connecting to VM via VNCESC[0m
ESC[1;32m==> qemu: Connecting to VM via VNCESC[0m
2017/12/13 16:14:16 packer-builder-qemu: 2017/12/13 16:14:16 Connected to VNC desktop: QEMU (packer-qemu)
2017/12/13 16:14:16 ui: ESC[1;32m==> qemu: Typing the boot command over VNC...ESC[0m
ESC[1;32m==> qemu: Typing the boot command over VNC...ESC[0m
2017/12/13 16:14:16 packer-builder-qemu: 2017/12/13 16:14:16 Special code '<enter>' found, replacing with: 65293
2017/12/13 16:14:17 packer-builder-qemu: 2017/12/13 16:14:17 Special code '<wait>' found, sleeping one second
2017/12/13 16:14:18 packer-builder-qemu: 2017/12/13 16:14:18 Sending char 'r', code 114, shift false
2017/12/13 16:14:18 packer-builder-qemu: 2017/12/13 16:14:18 Sending char 'o', code 111, shift false
2017/12/13 16:14:18 packer-builder-qemu: 2017/12/13 16:14:18 Sending char 'o', code 111, shift false
2017/12/13 16:14:19 packer-builder-qemu: 2017/12/13 16:14:19 Sending char 't', code 116, shift false
2017/12/13 16:14:19 packer-builder-qemu: 2017/12/13 16:14:19 Special code '<enter>' found, replacing with: 65293
2017/12/13 16:14:19 packer-builder-qemu: 2017/12/13 16:14:19 Sending char '<', code 60, shift true
2017/12/13 16:14:20 packer-builder-qemu: 2017/12/13 16:14:20 Sending char 'w', code 119, shift false
2017/12/13 16:14:20 packer-builder-qemu: 2017/12/13 16:14:20 Sending char 'a', code 97, shift false
2017/12/13 16:14:20 packer-builder-qemu: 2017/12/13 16:14:20 Sending char 'i', code 105, shift false
2017/12/13 16:14:21 packer-builder-qemu: 2017/12/13 16:14:21 Sending char 't', code 116, shift false
2017/12/13 16:14:21 packer-builder-qemu: 2017/12/13 16:14:21 Sending char '2', code 50, shift false
2017/12/13 16:14:21 packer-builder-qemu: 2017/12/13 16:14:21 Sending char 'm', code 109, shift false
2017/12/13 16:14:21 packer-builder-qemu: 2017/12/13 16:14:21 Sending char '>', code 62, shift true
2017/12/13 16:14:22 packer-builder-qemu: 2017/12/13 16:14:22 Special code '<wait10>' found, sleeping 10 seconds
2017/12/13 16:14:32 packer-builder-qemu: 2017/12/13 16:14:32 Special code '<wait10>' found, sleeping 10 seconds
^C2017/12/13 16:14:42 Stopping build: qemu
2017/12/13 16:14:42 Closing stdin because interrupt received.
2017/12/13 16:14:42 packer-builder-qemu: 2017/12/13 16:14:42 Received interrupt signal (count: 1). Ignoring.
2017/12/13 16:14:42 packer-builder-qemu: 2017/12/13 16:14:42 Cancelling the step runner...
2017/12/13 16:14:42 packer-builder-qemu: 2017/12/13 16:14:42 Qemu stderr: qemu-system-x86_64: terminating on signal 2
2017/12/13 16:14:42 ui: ESC[1;32m==> qemu: Deleting output directory...ESC[0m
ESC[1;32m==> qemu: Deleting output directory...ESC[0m
2017/12/13 16:14:42 Build cancelled: qemu
2017/12/13 16:14:42 [INFO] (telemetry) ending qemu
2017/12/13 16:14:42 [INFO] (telemetry) found error: Build was cancelled.
ESC[1;31mBuild 'qemu' errored: Build was cancelled.ESC[0m
2017/12/13 16:14:42 ui error: ESC[1;31mBuild 'qemu' errored: Build was cancelled.ESC[0m
2017/12/13 16:14:42 Builds completed. Waiting on interrupt barrier...
2017/12/13 16:14:42 ui: Cleanly cancelled builds after being interrupted.
Cleanly cancelled builds after being interrupted.
2017/12/13 16:14:42 [INFO] (telemetry) Finalizing.
2017/12/13 16:14:42 waiting for all plugin processes to complete...
2017/12/13 16:14:42 /usr/local/bin/packer-builder-qemu: plugin process exited
ESC]0;dh3@maybe: ~/alpine-linux-image^Gdh3@maybe:~/alpine-linux-image$ exit