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' '' 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' '' 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: text ks=http://10.0.2.2:8176/kick_start.cfg'. 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