2018/05/07 17:08:51 [INFO] Packer version: 1.2.3
2018/05/07 17:08:51 Packer Target OS/Arch: windows amd64
2018/05/07 17:08:51 Built with Go Version: go1.10.1
2018/05/07 17:08:51 Using internal plugin for docker
2018/05/07 17:08:51 Using internal plugin for oracle-classic
2018/05/07 17:08:51 Using internal plugin for scaleway
2018/05/07 17:08:51 Using internal plugin for triton
2018/05/07 17:08:51 Using internal plugin for virtualbox-ovf
2018/05/07 17:08:51 Using internal plugin for vmware-iso
2018/05/07 17:08:51 Using internal plugin for amazon-instance
2018/05/07 17:08:51 Using internal plugin for googlecompute
2018/05/07 17:08:51 Using internal plugin for virtualbox-iso
2018/05/07 17:08:51 Using internal plugin for alicloud-ecs
2018/05/07 17:08:51 Using internal plugin for amazon-ebs
2018/05/07 17:08:51 Using internal plugin for amazon-ebsvolume
2018/05/07 17:08:51 Using internal plugin for hyperv-iso
2018/05/07 17:08:51 Using internal plugin for amazon-chroot
2018/05/07 17:08:51 Using internal plugin for amazon-ebssurrogate
2018/05/07 17:08:51 Using internal plugin for cloudstack
2018/05/07 17:08:51 Using internal plugin for openstack
2018/05/07 17:08:51 Using internal plugin for azure-arm
2018/05/07 17:08:51 Using internal plugin for digitalocean
2018/05/07 17:08:51 Using internal plugin for hyperv-vmcx
2018/05/07 17:08:51 Using internal plugin for ncloud
2018/05/07 17:08:51 Using internal plugin for file
2018/05/07 17:08:51 Using internal plugin for lxd
2018/05/07 17:08:51 Using internal plugin for null
2018/05/07 17:08:51 Using internal plugin for parallels-iso
2018/05/07 17:08:51 Using internal plugin for parallels-pvm
2018/05/07 17:08:51 Using internal plugin for profitbricks
2018/05/07 17:08:51 Using internal plugin for lxc
2018/05/07 17:08:51 Using internal plugin for oneandone
2018/05/07 17:08:51 Using internal plugin for oracle-oci
2018/05/07 17:08:51 Using internal plugin for qemu
2018/05/07 17:08:51 Using internal plugin for vmware-vmx
2018/05/07 17:08:51 Using internal plugin for chef-solo
2018/05/07 17:08:51 Using internal plugin for file
2018/05/07 17:08:51 Using internal plugin for puppet-masterless
2018/05/07 17:08:51 Using internal plugin for shell
2018/05/07 17:08:51 Using internal plugin for windows-shell
2018/05/07 17:08:51 Using internal plugin for ansible-local
2018/05/07 17:08:51 Using internal plugin for chef-client
2018/05/07 17:08:51 Using internal plugin for converge
2018/05/07 17:08:51 Using internal plugin for salt-masterless
2018/05/07 17:08:51 Using internal plugin for ansible
2018/05/07 17:08:51 Using internal plugin for shell-local
2018/05/07 17:08:51 Using internal plugin for windows-restart
2018/05/07 17:08:51 Using internal plugin for powershell
2018/05/07 17:08:51 Using internal plugin for puppet-server
2018/05/07 17:08:51 Using internal plugin for amazon-import
2018/05/07 17:08:51 Using internal plugin for atlas
2018/05/07 17:08:51 Using internal plugin for shell-local
2018/05/07 17:08:51 Using internal plugin for vagrant
2018/05/07 17:08:51 Using internal plugin for vsphere
2018/05/07 17:08:51 Using internal plugin for vsphere-template
2018/05/07 17:08:51 Using internal plugin for alicloud-import
2018/05/07 17:08:51 Using internal plugin for googlecompute-export
2018/05/07 17:08:51 Using internal plugin for checksum
2018/05/07 17:08:51 Using internal plugin for compress
2018/05/07 17:08:51 Using internal plugin for docker-import
2018/05/07 17:08:51 Using internal plugin for docker-tag
2018/05/07 17:08:51 Using internal plugin for manifest
2018/05/07 17:08:51 Using internal plugin for artifice
2018/05/07 17:08:51 Using internal plugin for docker-push
2018/05/07 17:08:51 Using internal plugin for docker-save
2018/05/07 17:08:51 Using internal plugin for vagrant-cloud
2018/05/07 17:08:51 Attempting to open config file: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 [WARN] Config file doesn't exist: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[virtualbox-iso:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-iso alicloud-ecs:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-alicloud-ecs parallels-iso:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-iso profitbricks:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-profitbricks oneandone:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oneandone virtualbox-ovf:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-ovf hyperv-iso:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-iso azure-arm:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-arm oracle-oci:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-oci triton:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-triton googlecompute:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-googlecompute lxc:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxc vmware-vmx:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-vmx scaleway:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-scaleway vmware-iso:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-iso hyperv-vmcx:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-vmcx amazon-ebssurrogate:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebssurrogate ncloud:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-ncloud file:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-file lxd:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxd docker:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-docker amazon-instance:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-instance amazon-ebs:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebs qemu:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-qemu oracle-classic:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-classic amazon-ebsvolume:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebsvolume amazon-chroot:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-chroot cloudstack:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-cloudstack digitalocean:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-digitalocean parallels-pvm:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-pvm openstack:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-openstack null:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-builder-null] PostProcessors:map[atlas:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-atlas alicloud-import:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-alicloud-import checksum:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-checksum docker-save:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-save vagrant-cloud:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant-cloud compress:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-compress docker-import:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-import artifice:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-artifice amazon-import:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-amazon-import shell-local:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-shell-local vagrant:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant vsphere-template:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere-template vsphere:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere googlecompute-export:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-export docker-tag:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-tag manifest:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-manifest docker-push:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-push] Provisioners:map[converge:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-converge salt-masterless:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-salt-masterless windows-restart:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-restart chef-solo:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-solo shell:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell windows-shell:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-shell chef-client:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-client ansible:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible powershell:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-powershell puppet-server:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-server file:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-file puppet-masterless:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-masterless ansible-local:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible-local shell-local:C:\HashiCorp\Vagrant\bin\packer.exe-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell-local]}
2018/05/07 17:08:51 Setting cache directory: F:\packer_centos\gcc-6\debug\packer_cache
2018/05/07 17:08:51 Loading builder: virtualbox-iso
2018/05/07 17:08:51 Plugin could not be found. Checking same directory as executable.
2018/05/07 17:08:51 Current exe path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Creating plugin client for path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Starting plugin: C:\HashiCorp\Vagrant\bin\packer.exe []string{"C:\\HashiCorp\\Vagrant\\bin\\packer.exe", "plugin", "packer-builder-virtualbox-iso"}
2018/05/07 17:08:51 Waiting for RPC address for: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [INFO] Packer version: 1.2.3
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer Target OS/Arch: windows amd64
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Built with Go Version: go1.10.1
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Attempting to open config file: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [WARN] Config file doesn't exist: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Setting cache directory: F:\packer_centos\gcc-6\debug\packer_cache
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 args: []string{"packer-builder-virtualbox-iso"}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin minimum port: 10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin maximum port: 25000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin address: tcp 127.0.0.1:10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Waiting for connection...
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Serving a plugin connection...
2018/05/07 17:08:51 Loading provisioner: shell
2018/05/07 17:08:51 Plugin could not be found. Checking same directory as executable.
2018/05/07 17:08:51 Current exe path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Creating plugin client for path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Starting plugin: C:\HashiCorp\Vagrant\bin\packer.exe []string{"C:\\HashiCorp\\Vagrant\\bin\\packer.exe", "plugin", "packer-provisioner-shell"}
2018/05/07 17:08:51 Waiting for RPC address for: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [INFO] Packer version: 1.2.3
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer Target OS/Arch: windows amd64
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Built with Go Version: go1.10.1
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Attempting to open config file: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [WARN] Config file doesn't exist: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Setting cache directory: F:\packer_centos\gcc-6\debug\packer_cache
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 args: []string{"packer-provisioner-shell"}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin minimum port: 10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin maximum port: 25000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin address: tcp 127.0.0.1:10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Waiting for connection...
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Serving a plugin connection...
2018/05/07 17:08:51 Loading provisioner: shell
2018/05/07 17:08:51 Plugin could not be found. Checking same directory as executable.
2018/05/07 17:08:51 Current exe path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Creating plugin client for path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Starting plugin: C:\HashiCorp\Vagrant\bin\packer.exe []string{"C:\\HashiCorp\\Vagrant\\bin\\packer.exe", "plugin", "packer-provisioner-shell"}
2018/05/07 17:08:51 Waiting for RPC address for: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [INFO] Packer version: 1.2.3
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer Target OS/Arch: windows amd64
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Built with Go Version: go1.10.1
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Attempting to open config file: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [WARN] Config file doesn't exist: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Setting cache directory: F:\packer_centos\gcc-6\debug\packer_cache
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 args: []string{"packer-provisioner-shell"}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin minimum port: 10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin maximum port: 25000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin address: tcp 127.0.0.1:10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Waiting for connection...
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Serving a plugin connection...
2018/05/07 17:08:51 Loading provisioner: shell
2018/05/07 17:08:51 Plugin could not be found. Checking same directory as executable.
2018/05/07 17:08:51 Current exe path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Creating plugin client for path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Starting plugin: C:\HashiCorp\Vagrant\bin\packer.exe []string{"C:\\HashiCorp\\Vagrant\\bin\\packer.exe", "plugin", "packer-provisioner-shell"}
2018/05/07 17:08:51 Waiting for RPC address for: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [INFO] Packer version: 1.2.3
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer Target OS/Arch: windows amd64
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Built with Go Version: go1.10.1
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Attempting to open config file: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [WARN] Config file doesn't exist: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Setting cache directory: F:\packer_centos\gcc-6\debug\packer_cache
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 args: []string{"packer-provisioner-shell"}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin minimum port: 10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin maximum port: 25000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin address: tcp 127.0.0.1:10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Waiting for connection...
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Serving a plugin connection...
2018/05/07 17:08:51 Loading provisioner: shell
2018/05/07 17:08:51 Plugin could not be found. Checking same directory as executable.
2018/05/07 17:08:51 Current exe path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Creating plugin client for path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Starting plugin: C:\HashiCorp\Vagrant\bin\packer.exe []string{"C:\\HashiCorp\\Vagrant\\bin\\packer.exe", "plugin", "packer-provisioner-shell"}
2018/05/07 17:08:51 Waiting for RPC address for: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [INFO] Packer version: 1.2.3
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer Target OS/Arch: windows amd64
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Built with Go Version: go1.10.1
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Attempting to open config file: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [WARN] Config file doesn't exist: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Setting cache directory: F:\packer_centos\gcc-6\debug\packer_cache
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 args: []string{"packer-provisioner-shell"}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin minimum port: 10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin maximum port: 25000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin address: tcp 127.0.0.1:10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Waiting for connection...
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Serving a plugin connection...
2018/05/07 17:08:51 Loading post-processor: vagrant
2018/05/07 17:08:51 Plugin could not be found. Checking same directory as executable.
2018/05/07 17:08:51 Current exe path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Creating plugin client for path: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 Starting plugin: C:\HashiCorp\Vagrant\bin\packer.exe []string{"C:\\HashiCorp\\Vagrant\\bin\\packer.exe", "plugin", "packer-post-processor-vagrant"}
2018/05/07 17:08:51 Waiting for RPC address for: C:\HashiCorp\Vagrant\bin\packer.exe
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [INFO] Packer version: 1.2.3
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer Target OS/Arch: windows amd64
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Built with Go Version: go1.10.1
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Attempting to open config file: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [WARN] Config file doesn't exist: C:\Users\fomalhaut\AppData\Roaming\packer.config
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Setting cache directory: F:\packer_centos\gcc-6\debug\packer_cache
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 args: []string{"packer-post-processor-vagrant"}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin minimum port: 10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin maximum port: 25000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Plugin address: tcp 127.0.0.1:10000
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Waiting for connection...
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Serving a plugin connection...
2018/05/07 17:08:51 ui: Debug mode enabled. Builds will not be parallelized.
2018/05/07 17:08:51 ui: virtualbox-iso output will be in this color.
2018/05/07 17:08:51 ui: 
2018/05/07 17:08:51 Build debug mode: true
2018/05/07 17:08:51 Force build: true
2018/05/07 17:08:51 On error: abort
2018/05/07 17:08:51 Preparing build: virtualbox-iso
2018/05/07 17:08:51 Debug enabled, so waiting for build to finish: virtualbox-iso
2018/05/07 17:08:51 Starting build run: virtualbox-iso
2018/05/07 17:08:51 Running builder: virtualbox-iso
2018/05/07 17:08:51 [INFO] (telemetry) Starting builder virtualbox-iso
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [DEBUG] builder/virtualbox: VBOX_MSI_INSTALL_PATH = C:\Program Files\Oracle\VirtualBox\
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 VBoxManage path: C:\Program Files\Oracle\VirtualBox\VBoxManage.exe
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 VBoxManage --version output: 5.2.10r122406
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 VirtualBox version: 5.2.10
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 guest_additions_url is blank; querying driver for iso.
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Found Default Guest Additions ISO: C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Guest additions URL: file:///C:/Program%20Files/Oracle/VirtualBox/VBoxGuestAdditions.iso
2018/05/07 17:08:51 ui: ==> virtualbox-iso: Downloading or copying Guest additions
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Acquiring lock to download: file:///C:/Program%20Files/Oracle/VirtualBox/VBoxGuestAdditions.iso
2018/05/07 17:08:51 ui:     virtualbox-iso: Downloading or copying: file:///C:/Program%20Files/Oracle/VirtualBox/VBoxGuestAdditions.iso
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 Parsed URL: &url.URL{Scheme:"file", Opaque:"", User:(*url.Userinfo)(nil), Host:"", Path:"/C:/Program Files/Oracle/VirtualBox/VBoxGuestAdditions.iso", RawPath:"", ForceQuery:false, RawQuery:"", Fragment:""}
2018/05/07 17:08:51 packer.exe: 2018/05/07 17:08:51 [DEBUG] Using local file: C:/Program Files/Oracle/VirtualBox/VBoxGuestAdditions.iso
2018/05/07 17:08:51 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepDownloadGuestAdditions'. Press enter to continue.
2018/05/07 17:09:04 ui: ==> virtualbox-iso: Downloading or copying ISO
2018/05/07 17:09:04 packer.exe: 2018/05/07 17:09:04 Acquiring lock to download: http://ftp.riken.jp/Linux/centos/7.4.1708/isos/x86_64/CentOS-7-x86_64-Everything-1708.iso
2018/05/07 17:09:04 ui:     virtualbox-iso: Downloading or copying: http://ftp.riken.jp/Linux/centos/7.4.1708/isos/x86_64/CentOS-7-x86_64-Everything-1708.iso
2018/05/07 17:09:04 packer.exe: 2018/05/07 17:09:04 Parsed URL: &url.URL{Scheme:"http", Opaque:"", User:(*url.Userinfo)(nil), Host:"ftp.riken.jp", Path:"/Linux/centos/7.4.1708/isos/x86_64/CentOS-7-x86_64-Everything-1708.iso", RawPath:"", ForceQuery:false, RawQuery:"", Fragment:""}
2018/05/07 17:09:04 packer.exe: 2018/05/07 17:09:04 [DEBUG] Downloading: http://ftp.riken.jp/Linux/centos/7.4.1708/isos/x86_64/CentOS-7-x86_64-Everything-1708.iso
2018/05/07 17:09:04 packer.exe: 2018/05/07 17:09:04 Starting download over HTTP: http://ftp.riken.jp/Linux/centos/7.4.1708/isos/x86_64/CentOS-7-x86_64-Everything-1708.iso
2018/05/07 17:09:09 ui:     virtualbox-iso: Download progress: 1%
2018/05/07 17:09:14 ui:     virtualbox-iso: Download progress: 3%
2018/05/07 17:09:19 ui:     virtualbox-iso: Download progress: 5%
2018/05/07 17:09:24 ui:     virtualbox-iso: Download progress: 7%
2018/05/07 17:09:29 ui:     virtualbox-iso: Download progress: 8%
2018/05/07 17:09:34 ui:     virtualbox-iso: Download progress: 10%
2018/05/07 17:09:39 ui:     virtualbox-iso: Download progress: 11%
2018/05/07 17:09:44 ui:     virtualbox-iso: Download progress: 13%
2018/05/07 17:09:49 ui:     virtualbox-iso: Download progress: 15%
2018/05/07 17:09:54 ui:     virtualbox-iso: Download progress: 17%
2018/05/07 17:09:59 ui:     virtualbox-iso: Download progress: 19%
2018/05/07 17:10:04 ui:     virtualbox-iso: Download progress: 20%
2018/05/07 17:10:09 ui:     virtualbox-iso: Download progress: 22%
2018/05/07 17:10:14 ui:     virtualbox-iso: Download progress: 24%
2018/05/07 17:10:19 ui:     virtualbox-iso: Download progress: 26%
2018/05/07 17:10:24 ui:     virtualbox-iso: Download progress: 29%
2018/05/07 17:10:29 ui:     virtualbox-iso: Download progress: 31%
2018/05/07 17:10:34 ui:     virtualbox-iso: Download progress: 33%
2018/05/07 17:10:39 ui:     virtualbox-iso: Download progress: 35%
2018/05/07 17:10:44 ui:     virtualbox-iso: Download progress: 38%
2018/05/07 17:10:49 ui:     virtualbox-iso: Download progress: 40%
2018/05/07 17:10:54 ui:     virtualbox-iso: Download progress: 42%
2018/05/07 17:10:59 ui:     virtualbox-iso: Download progress: 45%
2018/05/07 17:11:04 ui:     virtualbox-iso: Download progress: 47%
2018/05/07 17:11:09 ui:     virtualbox-iso: Download progress: 49%
2018/05/07 17:11:14 ui:     virtualbox-iso: Download progress: 52%
2018/05/07 17:11:19 ui:     virtualbox-iso: Download progress: 54%
2018/05/07 17:11:24 ui:     virtualbox-iso: Download progress: 57%
2018/05/07 17:11:29 ui:     virtualbox-iso: Download progress: 59%
2018/05/07 17:11:34 ui:     virtualbox-iso: Download progress: 61%
2018/05/07 17:11:39 ui:     virtualbox-iso: Download progress: 64%
2018/05/07 17:11:44 ui:     virtualbox-iso: Download progress: 66%
2018/05/07 17:11:49 ui:     virtualbox-iso: Download progress: 69%
2018/05/07 17:11:54 ui:     virtualbox-iso: Download progress: 71%
2018/05/07 17:11:59 ui:     virtualbox-iso: Download progress: 73%
2018/05/07 17:12:04 ui:     virtualbox-iso: Download progress: 75%
2018/05/07 17:12:09 ui:     virtualbox-iso: Download progress: 77%
2018/05/07 17:12:14 ui:     virtualbox-iso: Download progress: 79%
2018/05/07 17:12:19 ui:     virtualbox-iso: Download progress: 81%
2018/05/07 17:12:24 ui:     virtualbox-iso: Download progress: 83%
2018/05/07 17:12:29 ui:     virtualbox-iso: Download progress: 85%
2018/05/07 17:12:34 ui:     virtualbox-iso: Download progress: 87%
2018/05/07 17:12:39 ui:     virtualbox-iso: Download progress: 89%
2018/05/07 17:12:44 ui:     virtualbox-iso: Download progress: 91%
2018/05/07 17:12:49 ui:     virtualbox-iso: Download progress: 93%
2018/05/07 17:12:54 ui:     virtualbox-iso: Download progress: 96%
2018/05/07 17:12:59 ui:     virtualbox-iso: Download progress: 97%
2018/05/07 17:13:04 ui:     virtualbox-iso: Download progress: 98%
2018/05/07 17:13:07 packer.exe: 2018/05/07 17:13:07 Verifying checksum of F:\packer_centos\gcc-6\debug\packer_cache\cd048b0510716c6b5f6fd31655a0721f6495191440a3e1df8832f9490eb4151b.iso
2018/05/07 17:13:09 ui:     virtualbox-iso: Download progress: 100%
2018/05/07 17:13:14 ui:     virtualbox-iso: Download progress: 100%
2018/05/07 17:13:19 ui:     virtualbox-iso: Download progress: 100%
2018/05/07 17:13:24 ui:     virtualbox-iso: Download progress: 100%
2018/05/07 17:13:26 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepDownload'. Press enter to continue.
2018/05/07 17:16:49 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepOutputDir'. Press enter to continue.
2018/05/07 17:16:57 packer.exe: 2018/05/07 17:16:57 No floppy files specified. Floppy disk will not be made.
2018/05/07 17:16:57 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepCreateFloppy'. Press enter to continue.
2018/05/07 17:17:00 packer.exe: 2018/05/07 17:17:00 Trying port: 8176
2018/05/07 17:17:00 ui: ==> virtualbox-iso: Starting HTTP server on port 8176
2018/05/07 17:17:00 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepHTTPServer'. Press enter to continue.
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 Suppressing annoying messages in VirtualBox
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 Executing VBoxManage: []string{"setextradata", "global", "GUI/RegistrationData", "triesLeft=0"}
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 stdout:
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 stderr:
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 Executing VBoxManage: []string{"setextradata", "global", "GUI/SuppressMessages", "confirmInputCapture,remindAboutAutoCapture,remindAboutMouseIntegrationOff,remindAboutMouseIntegrationOn,remindAboutWrongColorDepth"}
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 stdout:
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 stderr:
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 Executing VBoxManage: []string{"setextradata", "global", "GUI/UpdateCheckCount", "60"}
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 stdout:
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 stderr:
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 Executing VBoxManage: []string{"setextradata", "global", "GUI/UpdateDate", "1 d, 2019-01-01, stable"}
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 stdout:
2018/05/07 17:17:03 packer.exe: 2018/05/07 17:17:03 stderr:
2018/05/07 17:17:03 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepSuppressMessages'. Press enter to continue.
2018/05/07 17:17:06 ui: ==> virtualbox-iso: Creating virtual machine...
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 Executing VBoxManage: []string{"createvm", "--name", "packer-virtualbox-iso-1525680531", "--ostype", "RedHat_64", "--register"}
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 stdout: Virtual machine 'packer-virtualbox-iso-1525680531' is created and registered.
2018/05/07 17:17:06 packer.exe: UUID: 9c7a79a5-5fc3-4291-84a0-537f9027638c
2018/05/07 17:17:06 packer.exe: Settings file: 'F:\virtual_machines\packer-virtualbox-iso-1525680531\packer-virtualbox-iso-1525680531.vbox'
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 stderr:
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 Executing VBoxManage: []string{"modifyvm", "packer-virtualbox-iso-1525680531", "--boot1", "disk", "--boot2", "dvd", "--boot3", "none", "--boot4", "none"}
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 stdout:
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 stderr:
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 Executing VBoxManage: []string{"modifyvm", "packer-virtualbox-iso-1525680531", "--cpus", "1"}
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 stdout:
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 stderr:
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 Executing VBoxManage: []string{"modifyvm", "packer-virtualbox-iso-1525680531", "--memory", "512"}
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 stdout:
2018/05/07 17:17:06 packer.exe: 2018/05/07 17:17:06 stderr:
2018/05/07 17:17:06 ui: ask: ==> virtualbox-iso: Pausing after run of step 'stepCreateVM'. Press enter to continue.
2018/05/07 17:17:10 ui: ==> virtualbox-iso: Creating hard drive...
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 Executing VBoxManage: []string{"createhd", "--filename", "output-virtualbox-iso\\packer-virtualbox-iso-1525680531.vdi", "--size", "40000", "--format", "VDI", "--variant", "Standard"}
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 stdout: Medium created. UUID: ed5d523b-cab8-4e3c-a8e8-91296a79525a
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 stderr: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 Executing VBoxManage: []string{"storagectl", "packer-virtualbox-iso-1525680531", "--name", "IDE Controller", "--add", "ide"}
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 stdout:
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 stderr:
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 Executing VBoxManage: []string{"storageattach", "packer-virtualbox-iso-1525680531", "--storagectl", "IDE Controller", "--port", "0", "--device", "0", "--type", "hdd", "--medium", "output-virtualbox-iso\\packer-virtualbox-iso-1525680531.vdi", "--nonrotational", "off", "--discard", "off"}
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 stdout:
2018/05/07 17:17:10 packer.exe: 2018/05/07 17:17:10 stderr:
2018/05/07 17:17:10 ui: ask: ==> virtualbox-iso: Pausing after run of step 'stepCreateDisk'. Press enter to continue.
2018/05/07 17:17:13 packer.exe: 2018/05/07 17:17:13 Executing VBoxManage: []string{"storageattach", "packer-virtualbox-iso-1525680531", "--storagectl", "IDE Controller", "--port", "0", "--device", "1", "--type", "dvddrive", "--medium", "F:\\packer_centos\\gcc-6\\debug\\packer_cache\\cd048b0510716c6b5f6fd31655a0721f6495191440a3e1df8832f9490eb4151b.iso"}
2018/05/07 17:17:13 packer.exe: 2018/05/07 17:17:13 stdout:
2018/05/07 17:17:13 packer.exe: 2018/05/07 17:17:13 stderr:
2018/05/07 17:17:13 ui: ask: ==> virtualbox-iso: Pausing after run of step 'stepAttachISO'. Press enter to continue.
2018/05/07 17:17:15 packer.exe: 2018/05/07 17:17:15 Not attaching guest additions since we're uploading.
2018/05/07 17:17:15 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepAttachGuestAdditions'. Press enter to continue.
2018/05/07 17:17:20 packer.exe: 2018/05/07 17:17:20 Looking for available port between 5900 and 6000 on 127.0.0.1
2018/05/07 17:17:20 packer.exe: 2018/05/07 17:17:20 Trying port: 5906
2018/05/07 17:17:20 packer.exe: 2018/05/07 17:17:20 Executing VBoxManage: []string{"modifyvm", "packer-virtualbox-iso-1525680531", "--vrdeaddress", "127.0.0.1", "--vrdeauthtype", "null", "--vrde", "on", "--vrdeport", "5906"}
2018/05/07 17:17:20 packer.exe: 2018/05/07 17:17:20 stdout:
2018/05/07 17:17:20 packer.exe: 2018/05/07 17:17:20 stderr:
2018/05/07 17:17:20 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepConfigureVRDP'. Press enter to continue.
2018/05/07 17:17:22 packer.exe: 2018/05/07 17:17:22 No floppy disk, not attaching.
2018/05/07 17:17:22 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepAttachFloppy'. Press enter to continue.
2018/05/07 17:17:23 packer.exe: 2018/05/07 17:17:23 Looking for available communicator (SSH, WinRM, etc) port between 2222 and 4444
2018/05/07 17:17:23 packer.exe: 2018/05/07 17:17:23 Trying port: 2686
2018/05/07 17:17:23 ui: ==> virtualbox-iso: Creating forwarded port mapping for communicator (SSH, WinRM, etc) (host port 2686)
2018/05/07 17:17:23 packer.exe: 2018/05/07 17:17:23 Executing VBoxManage: []string{"modifyvm", "packer-virtualbox-iso-1525680531", "--natpf1", "packercomm,tcp,127.0.0.1,2686,,22"}
2018/05/07 17:17:23 packer.exe: 2018/05/07 17:17:23 stdout:
2018/05/07 17:17:23 packer.exe: 2018/05/07 17:17:23 stderr:
2018/05/07 17:17:23 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepForwardSSH'. Press enter to continue.
2018/05/07 17:17:29 ui: ==> virtualbox-iso: Executing custom VBoxManage commands...
2018/05/07 17:17:29 ui:     virtualbox-iso: Executing: modifyvm packer-virtualbox-iso-1525680531 --memory 16384
2018/05/07 17:17:29 packer.exe: 2018/05/07 17:17:29 Executing VBoxManage: []string{"modifyvm", "packer-virtualbox-iso-1525680531", "--memory", "16384"}
2018/05/07 17:17:29 packer.exe: 2018/05/07 17:17:29 stdout:
2018/05/07 17:17:29 packer.exe: 2018/05/07 17:17:29 stderr:
2018/05/07 17:17:29 ui:     virtualbox-iso: Executing: modifyvm packer-virtualbox-iso-1525680531 --cpus 8
2018/05/07 17:17:29 packer.exe: 2018/05/07 17:17:29 Executing VBoxManage: []string{"modifyvm", "packer-virtualbox-iso-1525680531", "--cpus", "8"}
2018/05/07 17:17:29 packer.exe: 2018/05/07 17:17:29 stdout:
2018/05/07 17:17:29 packer.exe: 2018/05/07 17:17:29 stderr:
2018/05/07 17:17:29 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepVBoxManage'. Press enter to continue.
2018/05/07 17:17:30 ui: ==> virtualbox-iso: Starting the virtual machine...
2018/05/07 17:17:30 packer.exe: 2018/05/07 17:17:30 Executing VBoxManage: []string{"startvm", "packer-virtualbox-iso-1525680531", "--type", "gui"}
2018/05/07 17:17:32 packer.exe: 2018/05/07 17:17:32 stdout: Waiting for VM "packer-virtualbox-iso-1525680531" to power on...
2018/05/07 17:17:32 packer.exe: VM "packer-virtualbox-iso-1525680531" has been successfully started.
2018/05/07 17:17:32 packer.exe: 2018/05/07 17:17:32 stderr:
2018/05/07 17:17:32 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepRun'. Press enter to continue.
2018/05/07 17:17:40 ui: ==> virtualbox-iso: Waiting 10s for boot...
2018/05/07 17:17:50 ui: ==> virtualbox-iso: Typing the boot command...
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Special code 'Press' '<tab>' found, replacing with: &{[0f] [8f]}
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char ' ', code '39b9', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 't', code '1494', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'e', code '1292', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'x', code '2dad', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 't', code '1494', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char ' ', code '39b9', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'k', code '25a5', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 's', code '1f9f', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '=', code '0d8d', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'h', code '23a3', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 't', code '1494', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 't', code '1494', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'p', code '1999', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char ':', code '2a27aaa7', shift true
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '/', code '35b5', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '/', code '35b5', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '1', code '0282', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '0', code '0b8b', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '.', code '34b4', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '0', code '0b8b', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '.', code '34b4', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '2', code '0383', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '.', code '34b4', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '2', code '0383', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char ':', code '2a27aaa7', shift true
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '8', code '0989', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '1', code '0282', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '7', code '0888', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '6', code '0787', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '/', code '35b5', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'k', code '25a5', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'i', code '1797', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'c', code '2eae', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'k', code '25a5', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '_', code '2a0caa8c', shift true
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 's', code '1f9f', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 't', code '1494', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'a', code '1e9e', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'r', code '1393', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 't', code '1494', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char '.', code '34b4', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'c', code '2eae', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'f', code '21a1', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Sending char 'g', code '22a2', shift false
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Special code 'Press' '<enter>' found, replacing with: &{[1c] [9c]}
2018/05/07 17:17:50 packer.exe: 2018/05/07 17:17:50 Executing VBoxManage: []string{"controlvm", "packer-virtualbox-iso-1525680531", "keyboardputscancode", "0f", "8f", "39", "b9", "14", "94", "12", "92", "2d", "ad", "14", "94", "39", "b9", "25", "a5", "1f", "9f", "0d", "8d", "23", "a3", "14", "94"}
2018/05/07 17:17:51 packer.exe: 2018/05/07 17:17:51 stdout:
2018/05/07 17:17:51 packer.exe: 2018/05/07 17:17:51 stderr:
2018/05/07 17:17:51 packer.exe: 2018/05/07 17:17:51 Executing VBoxManage: []string{"controlvm", "packer-virtualbox-iso-1525680531", "keyboardputscancode", "14", "94", "19", "99", "2a", "27", "aa", "a7", "35", "b5", "35", "b5", "02", "82", "0b", "8b", "34", "b4", "0b", "8b", "34", "b4", "03", "83"}
2018/05/07 17:17:51 packer.exe: 2018/05/07 17:17:51 stdout:
2018/05/07 17:17:51 packer.exe: 2018/05/07 17:17:51 stderr:
2018/05/07 17:17:51 packer.exe: 2018/05/07 17:17:51 Executing VBoxManage: []string{"controlvm", "packer-virtualbox-iso-1525680531", "keyboardputscancode", "34", "b4", "03", "83", "2a", "27", "aa", "a7", "09", "89", "02", "82", "08", "88", "07", "87", "35", "b5", "25", "a5", "17", "97", "2e", "ae"}
2018/05/07 17:17:51 packer.exe: 2018/05/07 17:17:51 stdout:
2018/05/07 17:17:51 packer.exe: 2018/05/07 17:17:51 stderr:
2018/05/07 17:17:52 packer.exe: 2018/05/07 17:17:52 Executing VBoxManage: []string{"controlvm", "packer-virtualbox-iso-1525680531", "keyboardputscancode", "25", "a5", "2a", "0c", "aa", "8c", "1f", "9f", "14", "94", "1e", "9e", "13", "93", "14", "94", "34", "b4", "2e", "ae", "21", "a1", "22", "a2"}
2018/05/07 17:17:52 packer.exe: 2018/05/07 17:17:52 stdout:
2018/05/07 17:17:52 packer.exe: 2018/05/07 17:17:52 stderr:
2018/05/07 17:17:52 packer.exe: 2018/05/07 17:17:52 Executing VBoxManage: []string{"controlvm", "packer-virtualbox-iso-1525680531", "keyboardputscancode", "1c", "9c"}
2018/05/07 17:17:52 packer.exe: 2018/05/07 17:17:52 stdout:
2018/05/07 17:17:52 packer.exe: 2018/05/07 17:17:52 stderr:
2018/05/07 17:17:52 packer.exe: 2018/05/07 17:17:52 [INFO] Waiting 1s
2018/05/07 17:17:53 ui: ask: ==> virtualbox-iso: Pausing after run of step 'boot_command: <tab> text ks=http://10.0.2.2:8176/kick_start.cfg<enter><wait>'. Press enter to continue.
2018/05/07 17:18:01 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepTypeBootCommand'. Press enter to continue.
2018/05/07 17:18:02 packer.exe: 2018/05/07 17:18:02 [INFO] Waiting for SSH, up to timeout: 2h46m40s
2018/05/07 17:18:02 ui: ==> virtualbox-iso: Waiting for SSH to become available...
2018/05/07 17:18:02 packer.exe: 2018/05/07 17:18:02 [INFO] Attempting SSH connection...
2018/05/07 17:18:02 packer.exe: 2018/05/07 17:18:02 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:18:02 packer.exe: 2018/05/07 17:18:02 [DEBUG] handshaking with SSH
2018/05/07 17:18:09 packer.exe: 2018/05/07 17:18:09 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51016->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:09 packer.exe: 2018/05/07 17:18:09 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51016->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:16 packer.exe: 2018/05/07 17:18:16 [INFO] Attempting SSH connection...
2018/05/07 17:18:16 packer.exe: 2018/05/07 17:18:16 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:18:16 packer.exe: 2018/05/07 17:18:16 [DEBUG] handshaking with SSH
2018/05/07 17:18:16 packer.exe: 2018/05/07 17:18:16 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51020->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:16 packer.exe: 2018/05/07 17:18:16 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51020->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:23 packer.exe: 2018/05/07 17:18:23 [INFO] Attempting SSH connection...
2018/05/07 17:18:23 packer.exe: 2018/05/07 17:18:23 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:18:23 packer.exe: 2018/05/07 17:18:23 [DEBUG] handshaking with SSH
2018/05/07 17:18:23 packer.exe: 2018/05/07 17:18:23 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51022->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:23 packer.exe: 2018/05/07 17:18:23 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51022->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:30 packer.exe: 2018/05/07 17:18:30 [INFO] Attempting SSH connection...
2018/05/07 17:18:30 packer.exe: 2018/05/07 17:18:30 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:18:30 packer.exe: 2018/05/07 17:18:30 [DEBUG] handshaking with SSH
2018/05/07 17:18:30 packer.exe: 2018/05/07 17:18:30 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51024->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:30 packer.exe: 2018/05/07 17:18:30 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51024->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:37 packer.exe: 2018/05/07 17:18:37 [INFO] Attempting SSH connection...
2018/05/07 17:18:37 packer.exe: 2018/05/07 17:18:37 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:18:37 packer.exe: 2018/05/07 17:18:37 [DEBUG] handshaking with SSH
2018/05/07 17:18:37 packer.exe: 2018/05/07 17:18:37 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51026->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:37 packer.exe: 2018/05/07 17:18:37 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51026->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:44 packer.exe: 2018/05/07 17:18:44 [INFO] Attempting SSH connection...
2018/05/07 17:18:44 packer.exe: 2018/05/07 17:18:44 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:18:44 packer.exe: 2018/05/07 17:18:44 [DEBUG] handshaking with SSH
2018/05/07 17:18:44 packer.exe: 2018/05/07 17:18:44 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51028->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:44 packer.exe: 2018/05/07 17:18:44 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51028->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:51 packer.exe: 2018/05/07 17:18:51 [INFO] Attempting SSH connection...
2018/05/07 17:18:51 packer.exe: 2018/05/07 17:18:51 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:18:51 packer.exe: 2018/05/07 17:18:51 [DEBUG] handshaking with SSH
2018/05/07 17:18:51 packer.exe: 2018/05/07 17:18:51 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51030->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:51 packer.exe: 2018/05/07 17:18:51 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51030->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:58 packer.exe: 2018/05/07 17:18:58 [INFO] Attempting SSH connection...
2018/05/07 17:18:58 packer.exe: 2018/05/07 17:18:58 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:18:58 packer.exe: 2018/05/07 17:18:58 [DEBUG] handshaking with SSH
2018/05/07 17:18:58 packer.exe: 2018/05/07 17:18:58 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51032->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:18:58 packer.exe: 2018/05/07 17:18:58 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51032->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:05 packer.exe: 2018/05/07 17:19:05 [INFO] Attempting SSH connection...
2018/05/07 17:19:05 packer.exe: 2018/05/07 17:19:05 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:19:05 packer.exe: 2018/05/07 17:19:05 [DEBUG] handshaking with SSH
2018/05/07 17:19:05 packer.exe: 2018/05/07 17:19:05 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51034->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:05 packer.exe: 2018/05/07 17:19:05 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51034->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:12 packer.exe: 2018/05/07 17:19:12 [INFO] Attempting SSH connection...
2018/05/07 17:19:12 packer.exe: 2018/05/07 17:19:12 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:19:12 packer.exe: 2018/05/07 17:19:12 [DEBUG] handshaking with SSH
2018/05/07 17:19:12 packer.exe: 2018/05/07 17:19:12 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51036->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:12 packer.exe: 2018/05/07 17:19:12 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51036->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:19 packer.exe: 2018/05/07 17:19:19 [INFO] Attempting SSH connection...
2018/05/07 17:19:19 packer.exe: 2018/05/07 17:19:19 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:19:19 packer.exe: 2018/05/07 17:19:19 [DEBUG] handshaking with SSH
2018/05/07 17:19:19 packer.exe: 2018/05/07 17:19:19 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51038->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:19 packer.exe: 2018/05/07 17:19:19 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51038->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:26 packer.exe: 2018/05/07 17:19:26 [INFO] Attempting SSH connection...
2018/05/07 17:19:26 packer.exe: 2018/05/07 17:19:26 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:19:26 packer.exe: 2018/05/07 17:19:26 [DEBUG] handshaking with SSH
2018/05/07 17:19:26 packer.exe: 2018/05/07 17:19:26 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51040->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:26 packer.exe: 2018/05/07 17:19:26 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51040->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:33 packer.exe: 2018/05/07 17:19:33 [INFO] Attempting SSH connection...
2018/05/07 17:19:33 packer.exe: 2018/05/07 17:19:33 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:19:33 packer.exe: 2018/05/07 17:19:33 [DEBUG] handshaking with SSH
2018/05/07 17:19:33 packer.exe: 2018/05/07 17:19:33 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51042->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:33 packer.exe: 2018/05/07 17:19:33 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51042->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:40 packer.exe: 2018/05/07 17:19:40 [INFO] Attempting SSH connection...
2018/05/07 17:19:40 packer.exe: 2018/05/07 17:19:40 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:19:40 packer.exe: 2018/05/07 17:19:40 [DEBUG] handshaking with SSH
2018/05/07 17:19:40 packer.exe: 2018/05/07 17:19:40 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51044->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:40 packer.exe: 2018/05/07 17:19:40 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51044->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:47 packer.exe: 2018/05/07 17:19:47 [INFO] Attempting SSH connection...
2018/05/07 17:19:47 packer.exe: 2018/05/07 17:19:47 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:19:47 packer.exe: 2018/05/07 17:19:47 [DEBUG] handshaking with SSH
2018/05/07 17:19:47 packer.exe: 2018/05/07 17:19:47 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51047->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:47 packer.exe: 2018/05/07 17:19:47 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51047->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:55 packer.exe: 2018/05/07 17:19:55 [INFO] Attempting SSH connection...
2018/05/07 17:19:55 packer.exe: 2018/05/07 17:19:55 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:19:55 packer.exe: 2018/05/07 17:19:55 [DEBUG] handshaking with SSH
2018/05/07 17:19:55 packer.exe: 2018/05/07 17:19:55 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51049->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:19:55 packer.exe: 2018/05/07 17:19:55 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51049->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:02 packer.exe: 2018/05/07 17:20:02 [INFO] Attempting SSH connection...
2018/05/07 17:20:02 packer.exe: 2018/05/07 17:20:02 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:02 packer.exe: 2018/05/07 17:20:02 [DEBUG] handshaking with SSH
2018/05/07 17:20:02 packer.exe: 2018/05/07 17:20:02 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51051->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:02 packer.exe: 2018/05/07 17:20:02 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51051->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:09 packer.exe: 2018/05/07 17:20:09 [INFO] Attempting SSH connection...
2018/05/07 17:20:09 packer.exe: 2018/05/07 17:20:09 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:09 packer.exe: 2018/05/07 17:20:09 [DEBUG] handshaking with SSH
2018/05/07 17:20:09 packer.exe: 2018/05/07 17:20:09 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51053->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:09 packer.exe: 2018/05/07 17:20:09 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51053->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:16 packer.exe: 2018/05/07 17:20:16 [INFO] Attempting SSH connection...
2018/05/07 17:20:16 packer.exe: 2018/05/07 17:20:16 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:16 packer.exe: 2018/05/07 17:20:16 [DEBUG] handshaking with SSH
2018/05/07 17:20:16 packer.exe: 2018/05/07 17:20:16 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51055->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:16 packer.exe: 2018/05/07 17:20:16 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51055->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:23 packer.exe: 2018/05/07 17:20:23 [INFO] Attempting SSH connection...
2018/05/07 17:20:23 packer.exe: 2018/05/07 17:20:23 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:23 packer.exe: 2018/05/07 17:20:23 [DEBUG] handshaking with SSH
2018/05/07 17:20:23 packer.exe: 2018/05/07 17:20:23 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51057->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:23 packer.exe: 2018/05/07 17:20:23 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51057->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:30 packer.exe: 2018/05/07 17:20:30 [INFO] Attempting SSH connection...
2018/05/07 17:20:30 packer.exe: 2018/05/07 17:20:30 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:30 packer.exe: 2018/05/07 17:20:30 [DEBUG] handshaking with SSH
2018/05/07 17:20:30 packer.exe: 2018/05/07 17:20:30 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51059->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:30 packer.exe: 2018/05/07 17:20:30 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51059->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:37 packer.exe: 2018/05/07 17:20:37 [INFO] Attempting SSH connection...
2018/05/07 17:20:37 packer.exe: 2018/05/07 17:20:37 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:37 packer.exe: 2018/05/07 17:20:37 [DEBUG] handshaking with SSH
2018/05/07 17:20:37 packer.exe: 2018/05/07 17:20:37 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51061->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:37 packer.exe: 2018/05/07 17:20:37 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51061->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:44 packer.exe: 2018/05/07 17:20:44 [INFO] Attempting SSH connection...
2018/05/07 17:20:44 packer.exe: 2018/05/07 17:20:44 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:44 packer.exe: 2018/05/07 17:20:44 [DEBUG] handshaking with SSH
2018/05/07 17:20:44 packer.exe: 2018/05/07 17:20:44 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51064->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:44 packer.exe: 2018/05/07 17:20:44 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51064->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:51 packer.exe: 2018/05/07 17:20:51 [INFO] Attempting SSH connection...
2018/05/07 17:20:51 packer.exe: 2018/05/07 17:20:51 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:51 packer.exe: 2018/05/07 17:20:51 [DEBUG] handshaking with SSH
2018/05/07 17:20:51 packer.exe: 2018/05/07 17:20:51 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51096->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:51 packer.exe: 2018/05/07 17:20:51 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51096->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:58 packer.exe: 2018/05/07 17:20:58 [INFO] Attempting SSH connection...
2018/05/07 17:20:58 packer.exe: 2018/05/07 17:20:58 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:20:58 packer.exe: 2018/05/07 17:20:58 [DEBUG] handshaking with SSH
2018/05/07 17:20:58 packer.exe: 2018/05/07 17:20:58 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51108->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:20:58 packer.exe: 2018/05/07 17:20:58 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51108->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:05 packer.exe: 2018/05/07 17:21:05 [INFO] Attempting SSH connection...
2018/05/07 17:21:05 packer.exe: 2018/05/07 17:21:05 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:21:05 packer.exe: 2018/05/07 17:21:05 [DEBUG] handshaking with SSH
2018/05/07 17:21:05 packer.exe: 2018/05/07 17:21:05 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51112->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:05 packer.exe: 2018/05/07 17:21:05 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51112->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:12 packer.exe: 2018/05/07 17:21:12 [INFO] Attempting SSH connection...
2018/05/07 17:21:12 packer.exe: 2018/05/07 17:21:12 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:21:12 packer.exe: 2018/05/07 17:21:12 [DEBUG] handshaking with SSH
2018/05/07 17:21:12 packer.exe: 2018/05/07 17:21:12 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51117->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:12 packer.exe: 2018/05/07 17:21:12 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51117->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:19 packer.exe: 2018/05/07 17:21:19 [INFO] Attempting SSH connection...
2018/05/07 17:21:19 packer.exe: 2018/05/07 17:21:19 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:21:19 packer.exe: 2018/05/07 17:21:19 [DEBUG] handshaking with SSH
2018/05/07 17:21:19 packer.exe: 2018/05/07 17:21:19 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51125->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:19 packer.exe: 2018/05/07 17:21:19 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51125->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:26 packer.exe: 2018/05/07 17:21:26 [INFO] Attempting SSH connection...
2018/05/07 17:21:26 packer.exe: 2018/05/07 17:21:26 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:21:26 packer.exe: 2018/05/07 17:21:26 [DEBUG] handshaking with SSH
2018/05/07 17:21:26 packer.exe: 2018/05/07 17:21:26 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51130->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:26 packer.exe: 2018/05/07 17:21:26 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51130->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:33 packer.exe: 2018/05/07 17:21:33 [INFO] Attempting SSH connection...
2018/05/07 17:21:33 packer.exe: 2018/05/07 17:21:33 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:21:33 packer.exe: 2018/05/07 17:21:33 [DEBUG] handshaking with SSH
2018/05/07 17:21:33 packer.exe: 2018/05/07 17:21:33 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51134->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:33 packer.exe: 2018/05/07 17:21:33 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51134->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:40 packer.exe: 2018/05/07 17:21:40 [INFO] Attempting SSH connection...
2018/05/07 17:21:40 packer.exe: 2018/05/07 17:21:40 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:21:40 packer.exe: 2018/05/07 17:21:40 [DEBUG] handshaking with SSH
2018/05/07 17:21:40 packer.exe: 2018/05/07 17:21:40 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51136->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:40 packer.exe: 2018/05/07 17:21:40 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51136->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:47 packer.exe: 2018/05/07 17:21:47 [INFO] Attempting SSH connection...
2018/05/07 17:21:47 packer.exe: 2018/05/07 17:21:47 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:21:47 packer.exe: 2018/05/07 17:21:47 [DEBUG] handshaking with SSH
2018/05/07 17:21:47 packer.exe: 2018/05/07 17:21:47 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51138->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:47 packer.exe: 2018/05/07 17:21:47 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51138->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:54 packer.exe: 2018/05/07 17:21:54 [INFO] Attempting SSH connection...
2018/05/07 17:21:54 packer.exe: 2018/05/07 17:21:54 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:21:54 packer.exe: 2018/05/07 17:21:54 [DEBUG] handshaking with SSH
2018/05/07 17:21:54 packer.exe: 2018/05/07 17:21:54 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51140->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:21:54 packer.exe: 2018/05/07 17:21:54 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51140->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:01 packer.exe: 2018/05/07 17:22:01 [INFO] Attempting SSH connection...
2018/05/07 17:22:01 packer.exe: 2018/05/07 17:22:01 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:01 packer.exe: 2018/05/07 17:22:01 [DEBUG] handshaking with SSH
2018/05/07 17:22:01 packer.exe: 2018/05/07 17:22:01 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51142->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:01 packer.exe: 2018/05/07 17:22:01 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51142->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:08 packer.exe: 2018/05/07 17:22:08 [INFO] Attempting SSH connection...
2018/05/07 17:22:08 packer.exe: 2018/05/07 17:22:08 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:08 packer.exe: 2018/05/07 17:22:08 [DEBUG] handshaking with SSH
2018/05/07 17:22:08 packer.exe: 2018/05/07 17:22:08 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51144->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:08 packer.exe: 2018/05/07 17:22:08 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51144->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:15 packer.exe: 2018/05/07 17:22:15 [INFO] Attempting SSH connection...
2018/05/07 17:22:15 packer.exe: 2018/05/07 17:22:15 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:15 packer.exe: 2018/05/07 17:22:15 [DEBUG] handshaking with SSH
2018/05/07 17:22:15 packer.exe: 2018/05/07 17:22:15 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51146->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:15 packer.exe: 2018/05/07 17:22:15 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51146->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:22 packer.exe: 2018/05/07 17:22:22 [INFO] Attempting SSH connection...
2018/05/07 17:22:22 packer.exe: 2018/05/07 17:22:22 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:22 packer.exe: 2018/05/07 17:22:22 [DEBUG] handshaking with SSH
2018/05/07 17:22:22 packer.exe: 2018/05/07 17:22:22 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51148->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:22 packer.exe: 2018/05/07 17:22:22 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51148->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:29 packer.exe: 2018/05/07 17:22:29 [INFO] Attempting SSH connection...
2018/05/07 17:22:29 packer.exe: 2018/05/07 17:22:29 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:29 packer.exe: 2018/05/07 17:22:29 [DEBUG] handshaking with SSH
2018/05/07 17:22:29 packer.exe: 2018/05/07 17:22:29 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51150->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:29 packer.exe: 2018/05/07 17:22:29 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51150->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:36 packer.exe: 2018/05/07 17:22:36 [INFO] Attempting SSH connection...
2018/05/07 17:22:36 packer.exe: 2018/05/07 17:22:36 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:36 packer.exe: 2018/05/07 17:22:36 [DEBUG] handshaking with SSH
2018/05/07 17:22:36 packer.exe: 2018/05/07 17:22:36 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51153->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:36 packer.exe: 2018/05/07 17:22:36 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51153->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:43 packer.exe: 2018/05/07 17:22:43 [INFO] Attempting SSH connection...
2018/05/07 17:22:43 packer.exe: 2018/05/07 17:22:43 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:43 packer.exe: 2018/05/07 17:22:43 [DEBUG] handshaking with SSH
2018/05/07 17:22:43 packer.exe: 2018/05/07 17:22:43 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51155->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:43 packer.exe: 2018/05/07 17:22:43 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51155->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:50 packer.exe: 2018/05/07 17:22:50 [INFO] Attempting SSH connection...
2018/05/07 17:22:50 packer.exe: 2018/05/07 17:22:50 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:50 packer.exe: 2018/05/07 17:22:50 [DEBUG] handshaking with SSH
2018/05/07 17:22:50 packer.exe: 2018/05/07 17:22:50 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51157->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:50 packer.exe: 2018/05/07 17:22:50 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51157->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:57 packer.exe: 2018/05/07 17:22:57 [INFO] Attempting SSH connection...
2018/05/07 17:22:57 packer.exe: 2018/05/07 17:22:57 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:22:57 packer.exe: 2018/05/07 17:22:57 [DEBUG] handshaking with SSH
2018/05/07 17:22:57 packer.exe: 2018/05/07 17:22:57 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51159->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:22:57 packer.exe: 2018/05/07 17:22:57 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51159->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:04 packer.exe: 2018/05/07 17:23:04 [INFO] Attempting SSH connection...
2018/05/07 17:23:04 packer.exe: 2018/05/07 17:23:04 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:23:04 packer.exe: 2018/05/07 17:23:04 [DEBUG] handshaking with SSH
2018/05/07 17:23:04 packer.exe: 2018/05/07 17:23:04 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51162->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:04 packer.exe: 2018/05/07 17:23:04 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51162->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:11 packer.exe: 2018/05/07 17:23:11 [INFO] Attempting SSH connection...
2018/05/07 17:23:11 packer.exe: 2018/05/07 17:23:11 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:23:11 packer.exe: 2018/05/07 17:23:11 [DEBUG] handshaking with SSH
2018/05/07 17:23:11 packer.exe: 2018/05/07 17:23:11 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51164->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:11 packer.exe: 2018/05/07 17:23:11 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51164->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:18 packer.exe: 2018/05/07 17:23:18 [INFO] Attempting SSH connection...
2018/05/07 17:23:18 packer.exe: 2018/05/07 17:23:18 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:23:18 packer.exe: 2018/05/07 17:23:18 [DEBUG] handshaking with SSH
2018/05/07 17:23:18 packer.exe: 2018/05/07 17:23:18 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51167->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:18 packer.exe: 2018/05/07 17:23:18 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51167->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:25 packer.exe: 2018/05/07 17:23:25 [INFO] Attempting SSH connection...
2018/05/07 17:23:25 packer.exe: 2018/05/07 17:23:25 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:23:25 packer.exe: 2018/05/07 17:23:25 [DEBUG] handshaking with SSH
2018/05/07 17:23:25 packer.exe: 2018/05/07 17:23:25 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51169->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:25 packer.exe: 2018/05/07 17:23:25 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51169->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:32 packer.exe: 2018/05/07 17:23:32 [INFO] Attempting SSH connection...
2018/05/07 17:23:32 packer.exe: 2018/05/07 17:23:32 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:23:32 packer.exe: 2018/05/07 17:23:32 [DEBUG] handshaking with SSH
2018/05/07 17:23:32 packer.exe: 2018/05/07 17:23:32 [ERROR] handshake error: ssh: handshake failed: read tcp 127.0.0.1:51171->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:32 packer.exe: 2018/05/07 17:23:32 [DEBUG] SSH handshake err: ssh: handshake failed: read tcp 127.0.0.1:51171->127.0.0.1:2686: wsarecv: An existing connection was forcibly closed by the remote host.
2018/05/07 17:23:39 packer.exe: 2018/05/07 17:23:39 [INFO] Attempting SSH connection...
2018/05/07 17:23:39 packer.exe: 2018/05/07 17:23:39 [DEBUG] reconnecting to TCP connection for SSH
2018/05/07 17:23:39 packer.exe: 2018/05/07 17:23:39 [DEBUG] handshaking with SSH
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] handshake complete!
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [INFO] no local agent socket, will not connect agent
2018/05/07 17:23:58 ui: ==> virtualbox-iso: Connected to SSH!
2018/05/07 17:23:58 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepConnect'. Press enter to continue.
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 VBoxManage --version output: 5.2.10r122406
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 VirtualBox version: 5.2.10
2018/05/07 17:23:58 ui: ==> virtualbox-iso: Uploading VirtualBox version info (5.2.10)
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] Opening new ssh session
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] Starting remote scp process:  scp -vt .
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] Started SCP session, beginning transfers...
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] Copying input data into temporary file so we can read the length
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] scp: Uploading .vbox_version: perms=C0644 size=6
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] SCP session complete, closing stdin pipe.
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] Waiting for SSH session to complete.
2018/05/07 17:23:58 packer.exe: 2018/05/07 17:23:58 [DEBUG] scp stderr (length 28): Sink: C0644 6 .vbox_version
2018/05/07 17:23:58 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepUploadVersion'. Press enter to continue.
2018/05/07 17:24:22 packer.exe: 2018/05/07 17:24:22 VBoxManage --version output: 5.2.10r122406
2018/05/07 17:24:22 packer.exe: 2018/05/07 17:24:22 VirtualBox version: 5.2.10
2018/05/07 17:24:22 ui: ==> virtualbox-iso: Uploading VirtualBox guest additions ISO...
2018/05/07 17:24:22 packer.exe: 2018/05/07 17:24:22 [DEBUG] Opening new ssh session
2018/05/07 17:24:22 packer.exe: 2018/05/07 17:24:22 [DEBUG] Starting remote scp process:  scp -vt .
2018/05/07 17:24:22 packer.exe: 2018/05/07 17:24:22 [DEBUG] Started SCP session, beginning transfers...
2018/05/07 17:24:22 packer.exe: 2018/05/07 17:24:22 [DEBUG] Copying input data into temporary file so we can read the length
2018/05/07 17:24:23 packer.exe: 2018/05/07 17:24:23 [DEBUG] scp: Uploading VBoxGuestAdditions.iso: perms=C0644 size=57974784
2018/05/07 17:24:23 packer.exe: 2018/05/07 17:24:23 [DEBUG] SCP session complete, closing stdin pipe.
2018/05/07 17:24:23 packer.exe: 2018/05/07 17:24:23 [DEBUG] Waiting for SSH session to complete.
2018/05/07 17:24:23 packer.exe: 2018/05/07 17:24:23 [DEBUG] scp stderr (length 44): Sink: C0644 57974784 VBoxGuestAdditions.iso
2018/05/07 17:24:23 ui: ask: ==> virtualbox-iso: Pausing after run of step 'StepUploadGuestAdditions'. Press enter to continue.
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 Running the provision hook
2018/05/07 17:24:25 [INFO] (telemetry) Starting provisioner shell
2018/05/07 17:24:25 ui: ==> virtualbox-iso: Provisioning with shell script: provisioning_script/config_sudoers.sh
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 Opening provisioning_script/config_sudoers.sh for reading
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] 60 bytes written for 'uploadData'
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Opening new ssh session
2018/05/07 17:24:25 [INFO] 60 bytes written for 'uploadData'
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Starting remote scp process:  scp -vt /tmp
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Started SCP session, beginning transfers...
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] scp: Uploading script_8946.sh: perms=C0666 size=60
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] SCP session complete, closing stdin pipe.
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Waiting for SSH session to complete.
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] scp stderr (length 30): Sink: C0666 60 script_8946.sh
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Opening new ssh session
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] starting remote command: chmod 0755 /tmp/script_8946.sh
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:25 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:25 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Opening new ssh session
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] starting remote command: echo 'vagrant' | sudo -S sh '/tmp/script_8946.sh'
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:25 [INFO] 0 bytes written for 'stderr'
2018/05/07 17:24:25 [INFO] 0 bytes written for 'stdout'
2018/05/07 17:24:25 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:25 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] 0 bytes written for 'stderr'
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] 0 bytes written for 'stdout'
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Opening new ssh session
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] starting remote command: rm -f /tmp/script_8946.sh
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:25 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:25 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:25 ui: ==> virtualbox-iso: Provisioning with shell script: provisioning_script/prepare_ssh.sh
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 Opening provisioning_script/prepare_ssh.sh for reading
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [INFO] 330 bytes written for 'uploadData'
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Opening new ssh session
2018/05/07 17:24:25 [INFO] 330 bytes written for 'uploadData'
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Starting remote scp process:  scp -vt /tmp
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Started SCP session, beginning transfers...
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] scp: Uploading script_8946.sh: perms=C0666 size=330
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] SCP session complete, closing stdin pipe.
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Waiting for SSH session to complete.
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] scp stderr (length 31): Sink: C0666 330 script_8946.sh
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] Opening new ssh session
2018/05/07 17:24:25 packer.exe: 2018/05/07 17:24:25 [DEBUG] starting remote command: chmod 0755 /tmp/script_8946.sh
2018/05/07 17:24:26 packer.exe: 2018/05/07 17:24:26 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:26 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:26 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:26 packer.exe: 2018/05/07 17:24:26 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:26 packer.exe: 2018/05/07 17:24:26 [DEBUG] Opening new ssh session
2018/05/07 17:24:26 packer.exe: 2018/05/07 17:24:26 [DEBUG] starting remote command: echo 'vagrant' | sudo -S sh '/tmp/script_8946.sh'
2018/05/07 17:24:26 ui:     virtualbox-iso: mkdir .ssh
2018/05/07 17:24:26 ui:     virtualbox-iso:   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
2018/05/07 17:24:26 ui:     virtualbox-iso:                                  Dload  Upload   Total   Spent    Left  Speed
2018/05/07 17:24:26 ui:     virtualbox-iso:   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
2018/05/07 17:24:27 ui:     virtualbox-iso: 100   409  100   409    0     0    523      0 --:--:-- --:--:-- --:--:--   523
2018/05/07 17:24:27 ui:     virtualbox-iso: downloaded .ssh/authorized_keys
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:27 [INFO] 476 bytes written for 'stderr'
2018/05/07 17:24:27 [INFO] 43 bytes written for 'stdout'
2018/05/07 17:24:27 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:27 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] 476 bytes written for 'stderr'
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] 43 bytes written for 'stdout'
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] Opening new ssh session
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] starting remote command: rm -f /tmp/script_8946.sh
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:27 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:27 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:27 ui: ==> virtualbox-iso: Provisioning with shell script: provisioning_script/install_guest_additions.sh
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 Opening provisioning_script/install_guest_additions.sh for reading
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] 145 bytes written for 'uploadData'
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] Opening new ssh session
2018/05/07 17:24:27 [INFO] 145 bytes written for 'uploadData'
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] Starting remote scp process:  scp -vt /tmp
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] Started SCP session, beginning transfers...
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] scp: Uploading script_8946.sh: perms=C0666 size=145
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] SCP session complete, closing stdin pipe.
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] Waiting for SSH session to complete.
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] scp stderr (length 31): Sink: C0666 145 script_8946.sh
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] Opening new ssh session
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] starting remote command: chmod 0755 /tmp/script_8946.sh
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:27 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:27 [INFO] RPC endpoint: Communicator ended with: 0
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [INFO] RPC client: Communicator ended with: 0
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] Opening new ssh session
2018/05/07 17:24:27 packer.exe: 2018/05/07 17:24:27 [DEBUG] starting remote command: echo 'vagrant' | sudo -S sh '/tmp/script_8946.sh'
2018/05/07 17:24:27 ui:     virtualbox-iso: mount: /dev/loop0 is write-protected, mounting read-only
2018/05/07 17:24:27 ui:     virtualbox-iso: Verifying archive integrity... All good.
2018/05/07 17:24:27 ui:     virtualbox-iso: Uncompressing VirtualBox 5.2.10 Guest Additions for Linux........
2018/05/07 17:24:27 ui:     virtualbox-iso: VirtualBox Guest Additions installer
2018/05/07 17:24:27 ui:     virtualbox-iso: Copying additional installer modules ...
2018/05/07 17:24:28 ui:     virtualbox-iso: Installing additional modules ...
2018/05/07 17:24:28 ui:     virtualbox-iso: VirtualBox Guest Additions: Building the VirtualBox Guest Additions kernel modules.
2018/05/07 17:24:43 ui:     virtualbox-iso: VirtualBox Guest Additions: Starting.
2018/05/07 17:24:43 packer.exe: 2018/05/07 17:24:43 [ERROR] Remote command exited without exit status or exit signal.
2018/05/07 17:24:43 packer.exe: 2018/05/07 17:24:43 [INFO] RPC endpoint: Communicator ended with: 2300218
2018/05/07 17:24:43 [INFO] 291 bytes written for 'stderr'
2018/05/07 17:24:43 [INFO] 107 bytes written for 'stdout'
2018/05/07 17:24:43 [INFO] RPC client: Communicator ended with: 2300218
2018/05/07 17:24:43 [INFO] RPC endpoint: Communicator ended with: 2300218
2018/05/07 17:24:43 packer.exe: 2018/05/07 17:24:43 [INFO] 291 bytes written for 'stderr'
2018/05/07 17:24:43 packer.exe: 2018/05/07 17:24:43 [INFO] 107 bytes written for 'stdout'
2018/05/07 17:24:43 packer.exe: 2018/05/07 17:24:43 [INFO] RPC client: Communicator ended with: 2300218
2018/05/07 17:24:43 [INFO] (telemetry) ending shell
2018/05/07 17:24:43 ui error: ==> virtualbox-iso: Step "StepProvision" failed, aborting...
2018/05/07 17:24:43 [INFO] (telemetry) ending virtualbox-iso
2018/05/07 17:24:43 ui error: Build 'virtualbox-iso' errored: unexpected EOF
2018/05/07 17:24:43 Waiting on builds to complete...
2018/05/07 17:24:43 Builds completed. Waiting on interrupt barrier...
2018/05/07 17:24:43 machine readable: error-count []string{"1"}
2018/05/07 17:24:43 ui error: 
==> Some builds didn't complete successfully and had errors:
2018/05/07 17:24:43 machine readable: virtualbox-iso,error []string{"unexpected EOF"}
2018/05/07 17:24:43 ui error: --> virtualbox-iso: unexpected EOF
2018/05/07 17:24:43 ui: 
==> Builds finished but no artifacts were created.
2018/05/07 17:24:43 [INFO] (telemetry) Finalizing.
2018/05/07 17:24:43 C:\HashiCorp\Vagrant\bin\packer.exe: plugin process exited
2018/05/07 17:24:44 waiting for all plugin processes to complete...
2018/05/07 17:24:44 C:\HashiCorp\Vagrant\bin\packer.exe: plugin process exited
2018/05/07 17:24:44 C:\HashiCorp\Vagrant\bin\packer.exe: plugin process exited
2018/05/07 17:24:44 C:\HashiCorp\Vagrant\bin\packer.exe: plugin process exited
2018/05/07 17:24:44 C:\HashiCorp\Vagrant\bin\packer.exe: plugin process exited
2018/05/07 17:24:44 C:\HashiCorp\Vagrant\bin\packer.exe: plugin process exited
