ProtoDroidBot/detectionlab_kali_051119_packer_build.log

## detectionlab_kali_051119_packer_build.log
2019/05/10 19:48:11 [INFO] Packer version: 1.4.0
2019/05/10 19:48:11 Packer Target OS/Arch: linux amd64
2019/05/10 19:48:11 Built with Go Version: go1.12
2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 Using internal plugin for amazon-instance
2019/05/10 19:48:11 Using internal plugin for docker
2019/05/10 19:48:11 Using internal plugin for hyperone
2019/05/10 19:48:11 Using internal plugin for triton
2019/05/10 19:48:11 Using internal plugin for ncloud
2019/05/10 19:48:11 Using internal plugin for oneandone
2019/05/10 19:48:11 Using internal plugin for virtualbox-ovf
2019/05/10 19:48:11 Using internal plugin for amazon-ebs
2019/05/10 19:48:11 Using internal plugin for amazon-ebsvolume
2019/05/10 19:48:11 Using internal plugin for cloudstack
2019/05/10 19:48:11 Using internal plugin for googlecompute
2019/05/10 19:48:11 Using internal plugin for lxc
2019/05/10 19:48:11 Using internal plugin for amazon-ebssurrogate
2019/05/10 19:48:11 Using internal plugin for openstack
2019/05/10 19:48:11 Using internal plugin for oracle-oci
2019/05/10 19:48:11 Using internal plugin for hcloud
2019/05/10 19:48:11 Using internal plugin for hyperv-iso
2019/05/10 19:48:11 Using internal plugin for parallels-pvm
2019/05/10 19:48:11 Using internal plugin for vagrant
2019/05/10 19:48:11 Using internal plugin for lxd
2019/05/10 19:48:11 Using internal plugin for profitbricks
2019/05/10 19:48:11 Using internal plugin for scaleway
2019/05/10 19:48:11 Using internal plugin for vmware-iso
2019/05/10 19:48:11 Using internal plugin for amazon-chroot
2019/05/10 19:48:11 Using internal plugin for azure-arm
2019/05/10 19:48:11 Using internal plugin for tencentcloud-cvm
2019/05/10 19:48:11 Using internal plugin for vmware-vmx
2019/05/10 19:48:11 Using internal plugin for file
2019/05/10 19:48:11 Using internal plugin for hyperv-vmcx
2019/05/10 19:48:11 Using internal plugin for parallels-iso
2019/05/10 19:48:11 Using internal plugin for virtualbox-iso
2019/05/10 19:48:11 Using internal plugin for alicloud-ecs
2019/05/10 19:48:11 Using internal plugin for digitalocean
2019/05/10 19:48:11 Using internal plugin for null
2019/05/10 19:48:11 Using internal plugin for oracle-classic
2019/05/10 19:48:11 Using internal plugin for qemu
2019/05/10 19:48:11 Using internal plugin for puppet-masterless
2019/05/10 19:48:11 Using internal plugin for puppet-server
2019/05/10 19:48:11 Using internal plugin for shell
2019/05/10 19:48:11 Using internal plugin for sleep
2019/05/10 19:48:11 Using internal plugin for breakpoint
2019/05/10 19:48:11 Using internal plugin for chef-client
2019/05/10 19:48:11 Using internal plugin for chef-solo
2019/05/10 19:48:11 Using internal plugin for inspec
2019/05/10 19:48:11 Using internal plugin for windows-restart
2019/05/10 19:48:11 Using internal plugin for windows-shell
2019/05/10 19:48:11 Using internal plugin for salt-masterless
2019/05/10 19:48:11 Using internal plugin for ansible
2019/05/10 19:48:11 Using internal plugin for file
2019/05/10 19:48:11 Using internal plugin for shell-local
2019/05/10 19:48:11 Using internal plugin for ansible-local
2019/05/10 19:48:11 Using internal plugin for converge
2019/05/10 19:48:11 Using internal plugin for powershell
2019/05/10 19:48:11 Using internal plugin for googlecompute-export
2019/05/10 19:48:11 Using internal plugin for googlecompute-import
2019/05/10 19:48:11 Using internal plugin for vsphere
2019/05/10 19:48:11 Using internal plugin for artifice
2019/05/10 19:48:11 Using internal plugin for checksum
2019/05/10 19:48:11 Using internal plugin for compress
2019/05/10 19:48:11 Using internal plugin for digitalocean-import
2019/05/10 19:48:11 Using internal plugin for vagrant
2019/05/10 19:48:11 Using internal plugin for vagrant-cloud
2019/05/10 19:48:11 Using internal plugin for docker-push
2019/05/10 19:48:11 Using internal plugin for docker-save
2019/05/10 19:48:11 Using internal plugin for docker-tag
2019/05/10 19:48:11 Using internal plugin for manifest
2019/05/10 19:48:11 Using internal plugin for amazon-import
2019/05/10 19:48:11 Using internal plugin for alicloud-import
2019/05/10 19:48:11 Using internal plugin for docker-import
2019/05/10 19:48:11 Using internal plugin for shell-local
2019/05/10 19:48:11 Using internal plugin for vsphere-template
2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 Attempting to open config file: /root/.packerconfig
2019/05/10 19:48:11 [WARN] Config file doesn't exist: /root/.packerconfig
2019/05/10 19:48:11 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[alicloud-ecs:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-alicloud-ecs amazon-chroot:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-chroot amazon-ebs:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebs amazon-ebssurrogate:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebssurrogate amazon-ebsvolume:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-ebsvolume amazon-instance:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-amazon-instance azure-arm:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-azure-arm cloudstack:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-cloudstack digitalocean:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-digitalocean docker:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-docker file:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-file googlecompute:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-googlecompute hcloud:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hcloud hyperone:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperone hyperv-iso:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-iso hyperv-vmcx:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-hyperv-vmcx lxc:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxc lxd:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-lxd ncloud:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-ncloud null:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-null oneandone:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oneandone openstack:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-openstack oracle-classic:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-classic oracle-oci:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-oracle-oci parallels-iso:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-iso parallels-pvm:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-parallels-pvm profitbricks:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-profitbricks qemu:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-qemu scaleway:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-scaleway tencentcloud-cvm:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-tencentcloud-cvm triton:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-triton vagrant:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vagrant virtualbox-iso:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-iso virtualbox-ovf:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-virtualbox-ovf vmware-iso:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-iso vmware-vmx:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-builder-vmware-vmx] PostProcessors:map[alicloud-import:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-alicloud-import amazon-import:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-amazon-import artifice:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-artifice checksum:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-checksum compress:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-compress digitalocean-import:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-digitalocean-import docker-import:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-import docker-push:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-push docker-save:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-save docker-tag:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-docker-tag googlecompute-export:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-export googlecompute-import:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-googlecompute-import manifest:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-manifest shell-local:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-shell-local vagrant:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant vagrant-cloud:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vagrant-cloud vsphere:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere vsphere-template:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-post-processor-vsphere-template] Provisioners:map[ansible:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible ansible-local:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-ansible-local breakpoint:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-breakpoint chef-client:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-client chef-solo:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-chef-solo converge:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-converge file:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-file inspec:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-inspec powershell:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-powershell puppet-masterless:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-masterless puppet-server:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-puppet-server salt-masterless:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-salt-masterless shell:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell shell-local:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-shell-local sleep:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-sleep windows-restart:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-restart windows-shell:/usr/bin/packer-PACKERSPACE-plugin-PACKERSPACE-packer-provisioner-windows-shell]}
2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 Setting cache directory: /root/github/DetectionLab/Packer/packer_cache
2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 Loading builder: virtualbox-iso
2019/05/10 19:48:11 Plugin could not be found. Checking same directory as executable.
2019/05/10 19:48:11 Current exe path: /usr/bin/packer
2019/05/10 19:48:11 Creating plugin client for path: /usr/bin/packer
2019/05/10 19:48:11 Starting plugin: /usr/bin/packer []string{"/usr/bin/packer", "plugin", "packer-builder-virtualbox-iso"}
2019/05/10 19:48:11 Waiting for RPC address for: /usr/bin/packer
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [INFO] Packer version: 1.4.0
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer Target OS/Arch: linux amd64
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Built with Go Version: go1.12
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Attempting to open config file: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [WARN] Config file doesn't exist: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Setting cache directory: /root/github/DetectionLab/Packer/packer_cache
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 args: []string{"packer-builder-virtualbox-iso"}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin minimum port: 10000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin maximum port: 25000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin address: unix /tmp/packer-plugin208886238
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Waiting for connection...
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Serving a plugin connection...
2019/05/10 19:48:11 Loading provisioner: windows-shell
2019/05/10 19:48:11 Plugin could not be found. Checking same directory as executable.
2019/05/10 19:48:11 Current exe path: /usr/bin/packer
2019/05/10 19:48:11 Creating plugin client for path: /usr/bin/packer
2019/05/10 19:48:11 Starting plugin: /usr/bin/packer []string{"/usr/bin/packer", "plugin", "packer-provisioner-windows-shell"}
2019/05/10 19:48:11 Waiting for RPC address for: /usr/bin/packer
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [INFO] Packer version: 1.4.0
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer Target OS/Arch: linux amd64
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Built with Go Version: go1.12
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Attempting to open config file: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [WARN] Config file doesn't exist: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Setting cache directory: /root/github/DetectionLab/Packer/packer_cache
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 args: []string{"packer-provisioner-windows-shell"}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin minimum port: 10000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin maximum port: 25000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin address: unix /tmp/packer-plugin357308737
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Waiting for connection...
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Serving a plugin connection...
2019/05/10 19:48:11 Loading provisioner: powershell
2019/05/10 19:48:11 Plugin could not be found. Checking same directory as executable.
2019/05/10 19:48:11 Current exe path: /usr/bin/packer
2019/05/10 19:48:11 Creating plugin client for path: /usr/bin/packer
2019/05/10 19:48:11 Starting plugin: /usr/bin/packer []string{"/usr/bin/packer", "plugin", "packer-provisioner-powershell"}
2019/05/10 19:48:11 Waiting for RPC address for: /usr/bin/packer
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [INFO] Packer version: 1.4.0
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer Target OS/Arch: linux amd64
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Built with Go Version: go1.12
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Attempting to open config file: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [WARN] Config file doesn't exist: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Setting cache directory: /root/github/DetectionLab/Packer/packer_cache
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 args: []string{"packer-provisioner-powershell"}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin minimum port: 10000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin maximum port: 25000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin address: unix /tmp/packer-plugin001894829
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Waiting for connection...
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Serving a plugin connection...
2019/05/10 19:48:11 Loading provisioner: windows-restart
2019/05/10 19:48:11 Plugin could not be found. Checking same directory as executable.
2019/05/10 19:48:11 Current exe path: /usr/bin/packer
2019/05/10 19:48:11 Creating plugin client for path: /usr/bin/packer
2019/05/10 19:48:11 Starting plugin: /usr/bin/packer []string{"/usr/bin/packer", "plugin", "packer-provisioner-windows-restart"}
2019/05/10 19:48:11 Waiting for RPC address for: /usr/bin/packer
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [INFO] Packer version: 1.4.0
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer Target OS/Arch: linux amd64
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Built with Go Version: go1.12
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Attempting to open config file: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [WARN] Config file doesn't exist: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Setting cache directory: /root/github/DetectionLab/Packer/packer_cache
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 args: []string{"packer-provisioner-windows-restart"}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin minimum port: 10000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin maximum port: 25000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin address: unix /tmp/packer-plugin023629867
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Waiting for connection...
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Serving a plugin connection...
2019/05/10 19:48:11 Loading provisioner: powershell
2019/05/10 19:48:11 Plugin could not be found. Checking same directory as executable.
2019/05/10 19:48:11 Current exe path: /usr/bin/packer
2019/05/10 19:48:11 Creating plugin client for path: /usr/bin/packer
2019/05/10 19:48:11 Starting plugin: /usr/bin/packer []string{"/usr/bin/packer", "plugin", "packer-provisioner-powershell"}
2019/05/10 19:48:11 Waiting for RPC address for: /usr/bin/packer
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [INFO] Packer version: 1.4.0
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer Target OS/Arch: linux amd64
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Built with Go Version: go1.12
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Attempting to open config file: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [WARN] Config file doesn't exist: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Setting cache directory: /root/github/DetectionLab/Packer/packer_cache
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 args: []string{"packer-provisioner-powershell"}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin minimum port: 10000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin maximum port: 25000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin address: unix /tmp/packer-plugin478997827
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Waiting for connection...
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Serving a plugin connection...
2019/05/10 19:48:11 Loading provisioner: windows-shell
2019/05/10 19:48:11 Plugin could not be found. Checking same directory as executable.
2019/05/10 19:48:11 Current exe path: /usr/bin/packer
2019/05/10 19:48:11 Creating plugin client for path: /usr/bin/packer
2019/05/10 19:48:11 Starting plugin: /usr/bin/packer []string{"/usr/bin/packer", "plugin", "packer-provisioner-windows-shell"}
2019/05/10 19:48:11 Waiting for RPC address for: /usr/bin/packer
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [INFO] Packer version: 1.4.0
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer Target OS/Arch: linux amd64
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Built with Go Version: go1.12
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Attempting to open config file: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [WARN] Config file doesn't exist: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Setting cache directory: /root/github/DetectionLab/Packer/packer_cache
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 args: []string{"packer-provisioner-windows-shell"}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin minimum port: 10000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin maximum port: 25000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin address: unix /tmp/packer-plugin072830448
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Waiting for connection...
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Serving a plugin connection...
2019/05/10 19:48:11 Loading post-processor: vagrant
2019/05/10 19:48:11 Plugin could not be found. Checking same directory as executable.
2019/05/10 19:48:11 Current exe path: /usr/bin/packer
2019/05/10 19:48:11 Creating plugin client for path: /usr/bin/packer
2019/05/10 19:48:11 Starting plugin: /usr/bin/packer []string{"/usr/bin/packer", "plugin", "packer-post-processor-vagrant"}
2019/05/10 19:48:11 Waiting for RPC address for: /usr/bin/packer
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [INFO] Packer version: 1.4.0
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer Target OS/Arch: linux amd64
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Built with Go Version: go1.12
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Attempting to open config file: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 [WARN] Config file doesn't exist: /root/.packerconfig
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Packer config: &{DisableCheckpoint:false DisableCheckpointSignature:false PluginMinPort:10000 PluginMaxPort:25000 Builders:map[] PostProcessors:map[] Provisioners:map[]}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Setting cache directory: /root/github/DetectionLab/Packer/packer_cache
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Detected home directory from env var: /root
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 args: []string{"packer-post-processor-vagrant"}
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin minimum port: 10000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin maximum port: 25000
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Plugin address: unix /tmp/packer-plugin474564563
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Waiting for connection...
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Serving a plugin connection...
2019/05/10 19:48:11 ui: [1;32mvirtualbox-iso output will be in this color.[0m
2019/05/10 19:48:11 ui:
2019/05/10 19:48:11 Build debug mode: false
2019/05/10 19:48:11 Force build: false
2019/05/10 19:48:11 On error:
2019/05/10 19:48:11 Preparing build: virtualbox-iso
2019/05/10 19:48:11 Waiting on builds to complete...
2019/05/10 19:48:11 Starting build run: virtualbox-iso
2019/05/10 19:48:11 Running builder: virtualbox-iso
2019/05/10 19:48:11 [INFO] (telemetry) Starting builder virtualbox-iso
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 VBoxManage path: /usr/bin/VBoxManage
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Not downloading guest additions since it is disabled.
2019/05/10 19:48:11 ui: [1;32m==> virtualbox-iso: Retrieving ISO[0m
2019/05/10 19:48:11 ui: [1;32m==> virtualbox-iso: Trying https://software-download.microsoft.com/download/pr/17134.1.180410-1804.rs4_release_CLIENTENTERPRISEEVAL_OEMRET_x64FRE_en-us.iso[0m
2019/05/10 19:48:11 packer: 2019/05/10 19:48:11 Acquiring lock for: https://software-download.microsoft.com/download/pr/17134.1.180410-1804.rs4_release_CLIENTENTERPRISEEVAL_OEMRET_x64FRE_en-us.iso?checksum=sha256%3A27e4feb9102f7f2b21ebdb364587902a70842fb550204019d1a14b120918e455 (/root/github/DetectionLab/Packer/packer_cache/452c532d5ce927ca17aca3862a196eebf63ccda4.iso.lock)
2019/05/10 19:48:11 ui: [1;32m==> virtualbox-iso: Trying https://software-download.microsoft.com/download/pr/17134.1.180410-1804.rs4_release_CLIENTENTERPRISEEVAL_OEMRET_x64FRE_en-us.iso?checksum=sha256%3A27e4feb9102f7f2b21ebdb364587902a70842fb550204019d1a14b120918e455[0m
2019/05/10 19:56:06 packer: 2019/05/10 19:56:06 closing
2019/05/10 19:56:18 ui: [1;32m==> virtualbox-iso: https://software-download.microsoft.com/download/pr/17134.1.180410-1804.rs4_release_CLIENTENTERPRISEEVAL_OEMRET_x64FRE_en-us.iso?checksum=sha256%3A27e4feb9102f7f2b21ebdb364587902a70842fb550204019d1a14b120918e455 => /root/github/DetectionLab/Packer/packer_cache/452c532d5ce927ca17aca3862a196eebf63ccda4.iso[0m
2019/05/10 19:56:18 ui: [1;32m==> virtualbox-iso: leaving retrieve loop for ISO[0m
2019/05/10 19:56:18 ui: [1;32m==> virtualbox-iso: Creating floppy disk...[0m
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Floppy path: /tmp/packer596730789
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Initializing block device backed by temporary file
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Formatting the block device with a FAT filesystem...
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Initializing FAT filesystem on block device
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Reading the root directory from the filesystem
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying files flatly from floppy_files[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./answer_files/10/Autounattend.xml[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./floppy/WindowsPowershell.lnk[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./floppy/PinTo10.exe[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/fixnetwork.ps1[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/MakeWindows10GreatAgain.ps1[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/MakeWindows10GreatAgain.reg[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/rearm-windows.ps1[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/disable-screensaver.ps1[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/disable-winrm.ps1[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/enable-winrm.ps1[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/microsoft-updates.bat[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/win-updates.ps1[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Copying file: ./scripts/oracle-cert.cer[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Done copying files from floppy_files[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Collecting paths from floppy_dirs[0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Resulting paths from floppy_dirs : [][0m
2019/05/10 19:56:18 ui: [0;32m    virtualbox-iso: Done copying paths from floppy_dirs[0m
2019/05/10 19:56:18 ui: [1;32m==> virtualbox-iso: Creating ephemeral key pair for SSH communicator...[0m
2019/05/10 19:56:18 ui: [1;32m==> virtualbox-iso: Created ephemeral SSH key pair for communicator[0m
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Suppressing annoying messages in VirtualBox
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Executing VBoxManage: []string{"setextradata", "global", "GUI/SuppressMessages", "confirmInputCapture,remindAboutAutoCapture,remindAboutMouseIntegrationOff,remindAboutMouseIntegrationOn,remindAboutWrongColorDepth"}
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stdout:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stderr:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Executing VBoxManage: []string{"setextradata", "global", "GUI/UpdateCheckCount", "60"}
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stdout:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stderr:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Executing VBoxManage: []string{"setextradata", "global", "GUI/UpdateDate", "1 d, 2020-01-01, stable"}
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stdout:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stderr:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Executing VBoxManage: []string{"setextradata", "global", "GUI/RegistrationData", "triesLeft=0"}
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stdout:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stderr:
2019/05/10 19:56:18 ui: [1;32m==> virtualbox-iso: Creating virtual machine...[0m
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Executing VBoxManage: []string{"createvm", "--name", "windows_10", "--ostype", "Windows81_64", "--register"}
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stdout: Virtual machine 'windows_10' is created and registered.
2019/05/10 19:56:18 packer: UUID: e4bdf875-84d8-457f-869c-b011e12b45e8
2019/05/10 19:56:18 packer: Settings file: '/root/VirtualBox VMs/windows_10/windows_10.vbox'
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stderr:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Executing VBoxManage: []string{"modifyvm", "windows_10", "--boot1", "disk", "--boot2", "dvd", "--boot3", "none", "--boot4", "none"}
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stdout:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 stderr:
2019/05/10 19:56:18 packer: 2019/05/10 19:56:18 Executing VBoxManage: []string{"modifyvm", "windows_10", "--cpus", "1"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"modifyvm", "windows_10", "--memory", "512"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"modifyvm", "windows_10", "--usb", "off"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"modifyvm", "windows_10", "--audio", "none"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 ui: [1;32m==> virtualbox-iso: Creating hard drive...[0m
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"createhd", "--filename", "output-virtualbox-iso/windows_10.vdi", "--size", "61440", "--format", "VDI", "--variant", "Standard"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout: Medium created. UUID: 8bd5a0e1-6476-408c-85ec-3823ce06db0c
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"storagectl", "windows_10", "--name", "IDE Controller", "--add", "ide"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"storageattach", "windows_10", "--storagectl", "IDE Controller", "--port", "0", "--device", "0", "--type", "hdd", "--medium", "output-virtualbox-iso/windows_10.vdi", "--nonrotational", "off", "--discard", "off"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"storageattach", "windows_10", "--storagectl", "IDE Controller", "--port", "0", "--device", "1", "--type", "dvddrive", "--medium", "/root/github/DetectionLab/Packer/packer_cache/452c532d5ce927ca17aca3862a196eebf63ccda4.iso"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Not attaching guest additions since we're uploading.
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Looking for available port between 5900 and 6000 on 127.0.0.1
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Trying port: 5980
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Found available port: 5980 on IP: 127.0.0.1
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"modifyvm", "windows_10", "--vrdeaddress", "127.0.0.1", "--vrdeauthtype", "null", "--vrde", "on", "--vrdeport", "5980"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Copying floppy to temp location: /tmp/virtualbox387756224/floppy.vfd
2019/05/10 19:56:19 ui: [1;32m==> virtualbox-iso: Attaching floppy disk...[0m
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"storagectl", "windows_10", "--name", "Floppy Controller", "--add", "floppy"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"storageattach", "windows_10", "--storagectl", "Floppy Controller", "--port", "0", "--device", "0", "--type", "fdd", "--medium", "/tmp/virtualbox387756224/floppy.vfd"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Looking for available communicator (SSH, WinRM, etc) port between 2222 and 4444
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Trying port: 4335
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Found available port: 4335 on IP: 127.0.0.1
2019/05/10 19:56:19 ui: [1;32m==> virtualbox-iso: Creating forwarded port mapping for communicator (SSH, WinRM, etc) (host port 4335)[0m
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"modifyvm", "windows_10", "--natpf1", "packercomm,tcp,127.0.0.1,4335,,5985"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 ui: [1;32m==> virtualbox-iso: Executing custom VBoxManage commands...[0m
2019/05/10 19:56:19 ui: [0;32m    virtualbox-iso: Executing: modifyvm windows_10 --memory 2048[0m
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"modifyvm", "windows_10", "--memory", "2048"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 ui: [0;32m    virtualbox-iso: Executing: modifyvm windows_10 --cpus 2[0m
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"modifyvm", "windows_10", "--cpus", "2"}
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stdout:
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 stderr:
2019/05/10 19:56:19 ui: [1;32m==> virtualbox-iso: Starting the virtual machine...[0m
2019/05/10 19:56:19 packer: 2019/05/10 19:56:19 Executing VBoxManage: []string{"startvm", "windows_10", "--type", "gui"}
2019/05/10 19:56:21 packer: 2019/05/10 19:56:21 stdout: Waiting for VM "windows_10" to power on...
2019/05/10 19:56:21 packer: VM "windows_10" has been successfully started.
2019/05/10 19:56:21 packer: 2019/05/10 19:56:21 stderr:
2019/05/10 19:56:21 ui: [1;32m==> virtualbox-iso: Waiting 6m0s for boot...[0m
2019/05/10 20:02:21 ui: [1;32m==> virtualbox-iso: Typing the boot command...[0m
2019/05/10 20:02:21 ui: [1;32m==> virtualbox-iso: Using winrm communicator to connect: 127.0.0.1[0m
2019/05/10 20:02:21 packer: 2019/05/10 20:02:21 Waiting for WinRM, up to timeout: 4h0m0s
2019/05/10 20:02:21 ui: [1;32m==> virtualbox-iso: Waiting for WinRM to become available...[0m
2019/05/10 20:02:26 packer: 2019/05/10 20:02:26 [INFO] Attempting WinRM connection...
2019/05/10 20:02:26 packer: 2019/05/10 20:02:26 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:03:43 packer: 2019/05/10 20:03:43 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:44838->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:03:43 packer: 2019/05/10 20:03:43 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:44838->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:03:48 packer: 2019/05/10 20:03:48 [INFO] Attempting WinRM connection...
2019/05/10 20:03:48 packer: 2019/05/10 20:03:48 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:05:04 packer: 2019/05/10 20:05:04 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:44976->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:05:04 packer: 2019/05/10 20:05:04 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:44976->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:05:09 packer: 2019/05/10 20:05:09 [INFO] Attempting WinRM connection...
2019/05/10 20:05:09 packer: 2019/05/10 20:05:09 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:06:27 packer: 2019/05/10 20:06:27 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45032->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:06:27 packer: 2019/05/10 20:06:27 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45032->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:06:32 packer: 2019/05/10 20:06:32 [INFO] Attempting WinRM connection...
2019/05/10 20:06:32 packer: 2019/05/10 20:06:32 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:07:50 packer: 2019/05/10 20:07:50 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45064->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:07:50 packer: 2019/05/10 20:07:50 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45064->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:07:55 packer: 2019/05/10 20:07:55 [INFO] Attempting WinRM connection...
2019/05/10 20:07:55 packer: 2019/05/10 20:07:55 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:09:11 packer: 2019/05/10 20:09:11 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45086->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:09:11 packer: 2019/05/10 20:09:11 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45086->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:09:16 packer: 2019/05/10 20:09:16 [INFO] Attempting WinRM connection...
2019/05/10 20:09:16 packer: 2019/05/10 20:09:16 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:10:31 packer: 2019/05/10 20:10:31 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45090->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:10:31 packer: 2019/05/10 20:10:31 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45090->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:10:36 packer: 2019/05/10 20:10:36 [INFO] Attempting WinRM connection...
2019/05/10 20:10:36 packer: 2019/05/10 20:10:36 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:11:51 packer: 2019/05/10 20:11:51 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45092->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:11:51 packer: 2019/05/10 20:11:51 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45092->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:11:56 packer: 2019/05/10 20:11:56 [INFO] Attempting WinRM connection...
2019/05/10 20:11:56 packer: 2019/05/10 20:11:56 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:13:13 packer: 2019/05/10 20:13:13 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45094->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:13:13 packer: 2019/05/10 20:13:13 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45094->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:13:18 packer: 2019/05/10 20:13:18 [INFO] Attempting WinRM connection...
2019/05/10 20:13:18 packer: 2019/05/10 20:13:18 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:14:33 packer: 2019/05/10 20:14:33 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45106->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:14:33 packer: 2019/05/10 20:14:33 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45106->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:14:38 packer: 2019/05/10 20:14:38 [INFO] Attempting WinRM connection...
2019/05/10 20:14:38 packer: 2019/05/10 20:14:38 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:16:01 packer: 2019/05/10 20:16:01 [ERROR] connection error: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45108->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:16:01 packer: 2019/05/10 20:16:01 [ERROR] WinRM connection err: unknown error Post http://127.0.0.1:4335/wsman: read tcp 127.0.0.1:45108->127.0.0.1:4335: read: connection reset by peer
2019/05/10 20:16:06 packer: 2019/05/10 20:16:06 [INFO] Attempting WinRM connection...
2019/05/10 20:16:06 packer: 2019/05/10 20:16:06 [DEBUG] connecting to remote shell using WinRM
2019/05/10 20:17:22 packer: 2019/05/10 20:17:22 Checking that WinRM is connected with: 'powershell.exe -EncodedCommand aQBmACAAKABUAGUAcwB0AC0AUABhAHQAaAAgAHYAYQByAGkAYQBiAGwAZQA6AGcAbABvAGIAYQBsADoAUAByAG8AZwByAGUAcwBzAFAAcgBlAGYAZQByAGUAbgBjAGUAKQB7ACQAUAByAG8AZwByAGUAcwBzAFAAcgBlAGYAZQByAGUAbgBjAGUAPQAnAFMAaQBsAGUAbgB0AGwAeQBDAG8AbgB0AGkAbgB1AGUAJwB9ADsAIABlAGMAaABvACAAIgBXAGkAbgBSAE0AIABjAG8AbgBuAGUAYwB0AGUAZAAuACIA'
2019/05/10 20:17:22 packer: 2019/05/10 20:17:22 [INFO] starting remote command: powershell.exe -EncodedCommand aQBmACAAKABUAGUAcwB0AC0AUABhAHQAaAAgAHYAYQByAGkAYQBiAGwAZQA6AGcAbABvAGIAYQBsADoAUAByAG8AZwByAGUAcwBzAFAAcgBlAGYAZQByAGUAbgBjAGUAKQB7ACQAUAByAG8AZwByAGUAcwBzAFAAcgBlAGYAZQByAGUAbgBjAGUAPQAnAFMAaQBsAGUAbgB0AGwAeQBDAG8AbgB0AGkAbgB1AGUAJwB9ADsAIABlAGMAaABvACAAIgBXAGkAbgBSAE0AIABjAG8AbgBuAGUAYwB0AGUAZAAuACIA
2019/05/10 20:17:24 packer: 2019/05/10 20:17:24 [INFO] command 'powershell.exe -EncodedCommand aQBmACAAKABUAGUAcwB0AC0AUABhAHQAaAAgAHYAYQByAGkAYQBiAGwAZQA6AGcAbABvAGIAYQBsADoAUAByAG8AZwByAGUAcwBzAFAAcgBlAGYAZQByAGUAbgBjAGUAKQB7ACQAUAByAG8AZwByAGUAcwBzAFAAcgBlAGYAZQByAGUAbgBjAGUAPQAnAFMAaQBsAGUAbgB0AGwAeQBDAG8AbgB0AGkAbgB1AGUAJwB9ADsAIABlAGMAaABvACAAIgBXAGkAbgBSAE0AIABjAG8AbgBuAGUAYwB0AGUAZAAuACIA' exited with code: 0
2019/05/10 20:17:24 packer: 2019/05/10 20:17:24 Connected to machine
2019/05/10 20:17:24 ui: [1;32m==> virtualbox-iso: Connected to WinRM![0m
2019/05/10 20:17:24 ui: [0;32m    virtualbox-iso: WinRM connected.[0m
2019/05/10 20:17:24 ui error: [1;31m==> virtualbox-iso: #< CLIXML[0m
2019/05/10 20:17:24 ui error: [1;31m==> virtualbox-iso: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj><Obj S="progress" RefId="1"><TNRef RefId="0" /><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>[0m
2019/05/10 20:17:24 packer: 2019/05/10 20:17:24 VBoxManage --version output: 6.0.6_Debianr129722
2019/05/10 20:17:24 packer: 2019/05/10 20:17:24 VirtualBox version: 6.0.6
2019/05/10 20:17:24 ui: [1;32m==> virtualbox-iso: Uploading VirtualBox version info (6.0.6)[0m
2019/05/10 20:17:24 packer: 2019/05/10 20:17:24 Uploading file to '.vbox_version'
2019/05/10 20:17:27 packer: #< CLIXML
2019/05/10 20:17:29 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:17:29 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:17:29 Not uploading guest additions since mode is not upload
2019/05/10 20:17:29 packer: 2019/05/10 20:17:29 Running the provision hook
2019/05/10 20:17:29 [INFO] (telemetry) Starting provisioner windows-shell
2019/05/10 20:17:29 ui: [1;32m==> virtualbox-iso: Provisioning with windows-shell...[0m
2019/05/10 20:17:29 ui: [1;32m==> virtualbox-iso: Provisioning with shell script: ./scripts/vm-guest-tools.bat[0m
2019/05/10 20:17:29 packer: 2019/05/10 20:17:29 Opening ./scripts/vm-guest-tools.bat for reading
2019/05/10 20:17:29 packer: 2019/05/10 20:17:29 [INFO] 2309 bytes written for 'uploadData'
2019/05/10 20:17:29 [INFO] 2309 bytes written for 'uploadData'
2019/05/10 20:17:29 packer: 2019/05/10 20:17:29 Uploading file to '/tmp/script.bat'
2019/05/10 20:17:31 packer: #< CLIXML
2019/05/10 20:17:32 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:17:33 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:17:33 [INFO] starting remote command: set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"
2019/05/10 20:17:33 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if not exist "C:\Windows\Temp\7z1900-x64.msi" (powershell -Command "(New-Object System.Net.WebClient).DownloadFile('https://www.7-zip.org/a/7z1900-x64.msi', 'C:\Windows\Temp\7z1900-x64.msi')"  0<NUL )[0m
2019/05/10 20:17:37 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if not exist "C:\Windows\Temp\7z1900-x64.msi" (powershell -Command "Start-Sleep 5 ; (New-Object System.Net.WebClient).DownloadFile('https://www.7-zip.org/a/7z1900-x64.msi', 'C:\Windows\Temp\7z1900-x64.msi')"  0<NUL )[0m
2019/05/10 20:17:37 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>msiexec /qb /i C:\Windows\Temp\7z1900-x64.msi[0m
2019/05/10 20:17:37 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if "virtualbox-iso" EQU "vmware-iso" goto :vmware[0m
2019/05/10 20:17:37 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if "virtualbox-iso" EQU "virtualbox-iso" goto :virtualbox[0m
2019/05/10 20:17:37 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if exist "C:\Users\vagrant\VBoxGuestAdditions.iso" (move /Y C:\Users\vagrant\VBoxGuestAdditions.iso C:\Windows\Temp )[0m
2019/05/10 20:17:37 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if not exist "C:\Windows\Temp\VBoxGuestAdditions.iso" (powershell -Command "(New-Object System.Net.WebClient).DownloadFile('https://download.virtualbox.org/virtualbox/5.2.26/VBoxGuestAdditions_5.2.26.iso', 'C:\Windows\Temp\VBoxGuestAdditions.iso')"  0<NUL )[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>cmd /c ""C:\Program Files\7-Zip\7z.exe" x C:\Windows\Temp\VBoxGuestAdditions.iso -oC:\Windows\Temp\virtualbox"[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: 7-Zip 19.00 (x64) : Copyright (c) 1999-2018 Igor Pavlov : 2019-02-21[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Scanning the drive for archives:[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: 1 file, 58433536 bytes (56 MiB)[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Extracting archive: C:\Windows\Temp\VBoxGuestAdditions.iso[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: WARNINGS:[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: There are data after the end of archive[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: --[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Path = C:\Windows\Temp\VBoxGuestAdditions.iso[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Type = Iso[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: WARNINGS:[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: There are data after the end of archive[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Physical Size = 58124288[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Tail Size = 309248[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Created = 2019-01-25 11:58:44[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Modified = 2019-01-25 11:58:44[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Everything is Ok[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Archives with Warnings: 1[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Warnings: 1[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Folders: 4[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Files: 27[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Size:       58016270[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: Compressed: 58433536[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>cmd /c for %i in (C:\Windows\Temp\virtualbox\cert\vbox*.cer) do C:\Windows\Temp\virtualbox\cert\VBoxCertUtil add-trusted-publisher %i --root %i[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>C:\Windows\Temp\virtualbox\cert\VBoxCertUtil add-trusted-publisher C:\Windows\Temp\virtualbox\cert\vbox-sha1.cer --root C:\Windows\Temp\virtualbox\cert\vbox-sha1.cer[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: VBoxCertUtil.exe: info: Successfully added 'C:\Windows\Temp\virtualbox\cert\vbox-sha1.cer' as trusted publisher[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: VBoxCertUtil.exe: info: Successfully added 'C:\Windows\Temp\virtualbox\cert\vbox-sha1.cer' as root[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>C:\Windows\Temp\virtualbox\cert\VBoxCertUtil add-trusted-publisher C:\Windows\Temp\virtualbox\cert\vbox-sha256.cer --root C:\Windows\Temp\virtualbox\cert\vbox-sha256.cer[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: VBoxCertUtil.exe: info: Successfully added 'C:\Windows\Temp\virtualbox\cert\vbox-sha256.cer' as trusted publisher[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: VBoxCertUtil.exe: info: Successfully added 'C:\Windows\Temp\virtualbox\cert\vbox-sha256.cer' as root[0m
2019/05/10 20:17:47 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>cmd /c C:\Windows\Temp\virtualbox\VBoxWindowsAdditions.exe /S[0m
2019/05/10 20:17:59 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>rd /S /Q "C:\Windows\Temp\virtualbox"[0m
2019/05/10 20:17:59 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>goto :done[0m
2019/05/10 20:17:59 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>msiexec /qb /x C:\Windows\Temp\7z1900-x64.msi[0m
2019/05/10 20:17:59 packer: 2019/05/10 20:17:59 [INFO] command 'set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"' exited with code: 0
2019/05/10 20:17:59 packer: 2019/05/10 20:17:59 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:17:59 [INFO] 3055 bytes written for 'stdout'
2019/05/10 20:17:59 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:17:59 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:17:59 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:17:59 packer: 2019/05/10 20:17:59 [INFO] 3055 bytes written for 'stdout'
2019/05/10 20:17:59 packer: 2019/05/10 20:17:59 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:17:59 packer: 2019/05/10 20:17:59 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:17:59 ui: [1;32m==> virtualbox-iso: Provisioning with shell script: ./scripts/enable-rdp.bat[0m
2019/05/10 20:17:59 packer: 2019/05/10 20:17:59 Opening ./scripts/enable-rdp.bat for reading
2019/05/10 20:17:59 packer: 2019/05/10 20:17:59 Uploading file to '/tmp/script.bat'
2019/05/10 20:17:59 packer: 2019/05/10 20:17:59 [INFO] 227 bytes written for 'uploadData'
2019/05/10 20:17:59 [INFO] 227 bytes written for 'uploadData'
2019/05/10 20:18:01 packer: #< CLIXML
2019/05/10 20:18:03 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:18:03 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:18:03 [INFO] starting remote command: set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 [INFO] command 'set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"' exited with code: 0
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:18:03 [INFO] 316 bytes written for 'stdout'
2019/05/10 20:18:03 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:18:03 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:18:03 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 [INFO] 316 bytes written for 'stdout'
2019/05/10 20:18:03 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>netsh advfirewall firewall add rule name="Open Port 3389" dir=in action=allow protocol=TCP localport=3389[0m
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 Error in Ui.Message RPC call: connection is shut down
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 Error in Ui.Message RPC call: connection is shut down
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 Error in Ui.Message RPC call: connection is shut down
2019/05/10 20:18:03 [INFO] (telemetry) ending windows-shell
2019/05/10 20:18:03 [INFO] (telemetry) Starting provisioner powershell
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 Error in Ui.Message RPC call: connection is shut down
2019/05/10 20:18:03 ui: [1;32m==> virtualbox-iso: Provisioning with Powershell...[0m
2019/05/10 20:18:03 ui: [1;32m==> virtualbox-iso: Provisioning with powershell script: ./scripts/debloat-windows.ps1[0m
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 Opening ./scripts/debloat-windows.ps1 for reading
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 Uploading env vars to c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:18:03 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:18:03 packer: 2019/05/10 20:18:03 Uploading file to 'c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1'
2019/05/10 20:18:06 packer: #< CLIXML
2019/05/10 20:18:07 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:18:07 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:18:07 Uploading file to 'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'
2019/05/10 20:18:07 [INFO] 1526 bytes written for 'uploadData'
2019/05/10 20:18:07 packer: 2019/05/10 20:18:07 [INFO] 1526 bytes written for 'uploadData'
2019/05/10 20:18:10 packer: #< CLIXML
2019/05/10 20:18:12 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:18:12 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:18:12 [INFO] starting remote command: powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1; &'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'; exit $LastExitCode }"
2019/05/10 20:18:14 ui: [0;32m    virtualbox-iso: Downloading debloat zip[0m
2019/05/10 20:18:17 ui: [0;32m    virtualbox-iso: Disable Windows Defender[0m
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 [INFO] command 'powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1; &'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'; exit $LastExitCode }"' exited with code: 0
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:18:18 [INFO] 2075 bytes written for 'stdout'
2019/05/10 20:18:18 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:18:18 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Optimize Windows Update[0m
2019/05/10 20:18:18 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 [INFO] 2075 bytes written for 'stdout'
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Disable automatic download and installation of Windows updates[0m
2019/05/10 20:18:18 ui: [1;32m==> virtualbox-iso: Provisioning with powershell script: ./scripts/rearm-windows.ps1[0m
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 Opening ./scripts/rearm-windows.ps1 for reading
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 Uploading env vars to c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso:     Hive: HKEY_LOCAL_MACHINE\SOFTWARE\Wow6432Node\Policies\Microsoft\Windows\WindowsUpdate[0m
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:18:18 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Name                           Property[0m
2019/05/10 20:18:18 packer: 2019/05/10 20:18:18 Uploading file to 'c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1'
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: ----                           --------[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: AU[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Disable seeding of updates to other computers via Group Policies[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso:     Hive: HKEY_LOCAL_MACHINE\SOFTWARE\Policies\Microsoft\Windows[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Name                           Property[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: ----                           --------[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: DeliveryOptimization[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Disable 'Updates are available' message[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: SUCCESS: The file (or folder): "C:\Windows\System32\MusNotification.exe" now owned by user "VAGRANT-10\vagrant".[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: processed file: C:\Windows\System32\MusNotification.exe[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Successfully processed 1 files; Failed processing 0 files[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: SUCCESS: The file (or folder): "C:\Windows\System32\MusNotificationUx.exe" now owned by user "VAGRANT-10\vagrant".[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: processed file: C:\Windows\System32\MusNotificationUx.exe[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Successfully processed 1 files; Failed processing 0 files[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Disable Windows Update[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso:     Hive: HKEY_LOCAL_MACHINE\SOFTWARE\Policies\Microsoft\Windows NT[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Name                           Property[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: ----                           --------[0m
2019/05/10 20:18:18 ui: [0;32m    virtualbox-iso: Reliability[0m
2019/05/10 20:18:20 packer: #< CLIXML
2019/05/10 20:18:22 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:18:22 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:18:22 Uploading file to 'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'
2019/05/10 20:18:22 packer: 2019/05/10 20:18:22 [INFO] 285 bytes written for 'uploadData'
2019/05/10 20:18:22 [INFO] 285 bytes written for 'uploadData'
2019/05/10 20:18:24 packer: #< CLIXML
2019/05/10 20:18:26 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:18:26 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:18:26 [INFO] starting remote command: powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1; &'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'; exit $LastExitCode }"
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: Resetting the Windows evaluation timer[0m
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 [INFO] command 'powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1; &'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'; exit $LastExitCode }"' exited with code: 0
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:18:29 [INFO] 325 bytes written for 'stdout'
2019/05/10 20:18:29 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __GENUS          : 2[0m
2019/05/10 20:18:29 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:18:29 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 [INFO] 325 bytes written for 'stdout'
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __CLASS          : __PARAMETERS[0m
2019/05/10 20:18:29 ui: [1;32m==> virtualbox-iso: Provisioning with powershell script: ./scripts/MakeWindows10GreatAgain.ps1[0m
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 Opening ./scripts/MakeWindows10GreatAgain.ps1 for reading
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __SUPERCLASS     :[0m
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 Uploading env vars to c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __DYNASTY        : __PARAMETERS[0m
2019/05/10 20:18:29 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:18:29 packer: 2019/05/10 20:18:29 Uploading file to 'c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1'
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __RELPATH        :[0m
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __PROPERTY_COUNT : 1[0m
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __DERIVATION     : {}[0m
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __SERVER         :[0m
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __NAMESPACE      :[0m
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: __PATH           :[0m
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: ReturnValue      :[0m
2019/05/10 20:18:29 ui: [0;32m    virtualbox-iso: PSComputerName   :[0m
2019/05/10 20:18:31 packer: #< CLIXML
2019/05/10 20:18:33 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:18:33 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:18:33 Uploading file to 'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'
2019/05/10 20:18:33 [INFO] 1310 bytes written for 'uploadData'
2019/05/10 20:18:33 packer: 2019/05/10 20:18:33 [INFO] 1310 bytes written for 'uploadData'
2019/05/10 20:18:35 packer: #< CLIXML
2019/05/10 20:18:37 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:18:37 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:18:37 [INFO] starting remote command: powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1; &'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'; exit $LastExitCode }"
2019/05/10 20:18:39 ui: [0;32m    virtualbox-iso: Making Windows 10 Great again[0m
2019/05/10 20:18:39 ui: [0;32m    virtualbox-iso: Importing registry keys...[0m
2019/05/10 20:18:39 ui: [0;32m    virtualbox-iso: Removing OneDrive...[0m
2019/05/10 20:18:39 ui: [0;32m    virtualbox-iso: SUCCESS: The process "OneDrive.exe" with PID 6088 has been terminated.[0m
2019/05/10 20:18:39 ui: [0;32m    virtualbox-iso: Running Update-Help...[0m
2019/05/10 20:20:44 ui: [0;32m    virtualbox-iso: Removing Microsoft Store, Mail, and Edge shortcuts from the taskbar...[0m
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] command 'powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-4ac2-10d9-b179-942b7bd04ca5.ps1; &'c:/Windows/Temp/script-5cd60dbb-0171-4668-f750-45c3af88ae47.ps1'; exit $LastExitCode }"' exited with code: 0
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:20:44 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:20:44 [INFO] 244 bytes written for 'stdout'
2019/05/10 20:20:44 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:20:44 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] 244 bytes written for 'stdout'
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:20:44 [INFO] (telemetry) ending powershell
2019/05/10 20:20:44 [INFO] (telemetry) Starting provisioner windows-restart
2019/05/10 20:20:44 ui: [1;32m==> virtualbox-iso: Restarting Machine[0m
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] starting remote command: shutdown /r /f /t 0 /c "packer restart"
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] command 'shutdown /r /f /t 0 /c "packer restart"' exited with code: 0
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:20:44 [INFO] 0 bytes written for 'stdout'
2019/05/10 20:20:44 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:20:44 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:20:44 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] 0 bytes written for 'stdout'
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:20:44 packer: 2019/05/10 20:20:44 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:20:44 ui: [1;32m==> virtualbox-iso: Waiting for machine to restart...[0m
2019/05/10 20:20:45 packer: 2019/05/10 20:20:45 Check if machine is rebooting...
2019/05/10 20:20:45 packer: 2019/05/10 20:20:45 [INFO] starting remote command: shutdown /r /f /t 60 /c "packer restart test"
2019/05/10 20:20:45 packer: 2019/05/10 20:20:45 [INFO] command 'shutdown /r /f /t 60 /c "packer restart test"' exited with code: 1115
2019/05/10 20:20:45 packer: 2019/05/10 20:20:45 [INFO] RPC endpoint: Communicator ended with: 1115
2019/05/10 20:20:45 [INFO] 0 bytes written for 'stdout'
2019/05/10 20:20:45 [INFO] 40 bytes written for 'stderr'
2019/05/10 20:20:45 ui error: [1;31m==> virtualbox-iso: A system shutdown is in progress.(1115)[0m
2019/05/10 20:20:45 [INFO] RPC client: Communicator ended with: 1115
2019/05/10 20:20:45 [INFO] RPC endpoint: Communicator ended with: 1115
2019/05/10 20:20:45 packer: 2019/05/10 20:20:45 [INFO] 40 bytes written for 'stderr'
2019/05/10 20:20:45 packer: 2019/05/10 20:20:45 [INFO] 0 bytes written for 'stdout'
2019/05/10 20:20:45 packer: 2019/05/10 20:20:45 [INFO] RPC client: Communicator ended with: 1115
2019/05/10 20:20:45 packer: 2019/05/10 20:20:45 Reboot already in progress, waiting...
2019/05/10 20:20:55 packer: 2019/05/10 20:20:55 Check if machine is rebooting...
2019/05/10 20:22:15 [INFO] 0 bytes written for 'stdout'
2019/05/10 20:22:15 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:22:15 packer: 2019/05/10 20:22:15 [INFO] 0 bytes written for 'stdout'
2019/05/10 20:22:15 packer: 2019/05/10 20:22:15 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:22:15 packer: 2019/05/10 20:22:15 Waiting for machine to reboot with timeout: 5m0s
2019/05/10 20:22:15 packer: 2019/05/10 20:22:15 Waiting for machine to become available...
2019/05/10 20:22:15 packer: 2019/05/10 20:22:15 Checking that communicator is connected with: 'powershell.exe -EncodedCommand ZQBjAGgAbwAgACIAJAB7AGUAbgB2ADoAQwBPAE0AUABVAFQARQBSAE4AQQBNAEUAfQAgAHIAZQBzAHQAYQByAHQAZQBkAC4AIgA='
2019/05/10 20:23:14 packer: 2019/05/10 20:23:14 [INFO] starting remote command: powershell.exe -EncodedCommand ZQBjAGgAbwAgACIAJAB7AGUAbgB2ADoAQwBPAE0AUABVAFQARQBSAE4AQQBNAEUAfQAgAHIAZQBzAHQAYQByAHQAZQBkAC4AIgA=
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 [INFO] command 'powershell.exe -EncodedCommand ZQBjAGgAbwAgACIAJAB7AGUAbgB2ADoAQwBPAE0AUABVAFQARQBSAE4AQQBNAEUAfQAgAHIAZQBzAHQAYQByAHQAZQBkAC4AIgA=' exited with code: 0
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:23:16 [INFO] 23 bytes written for 'stdout'
2019/05/10 20:23:16 [INFO] 392 bytes written for 'stderr'
2019/05/10 20:23:16 ui: [0;32m    virtualbox-iso: VAGRANT-10 restarted.[0m
2019/05/10 20:23:16 ui error: [1;31m==> virtualbox-iso: #< CLIXML[0m
2019/05/10 20:23:16 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:23:16 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 [INFO] 23 bytes written for 'stdout'
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 [INFO] 392 bytes written for 'stderr'
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:23:16 ui: [1;32m==> virtualbox-iso: Machine successfully restarted, moving on[0m
2019/05/10 20:23:16 ui error: [1;31m==> virtualbox-iso: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>[0m
2019/05/10 20:23:16 [INFO] (telemetry) ending windows-restart
2019/05/10 20:23:16 [INFO] (telemetry) Starting provisioner powershell
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 Error in Ui.Error RPC call: connection is shut down
2019/05/10 20:23:16 ui: [1;32m==> virtualbox-iso: Provisioning with Powershell...[0m
2019/05/10 20:23:16 ui: [1;32m==> virtualbox-iso: Provisioning with powershell script: ./scripts/set-powerplan.ps1[0m
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 Opening ./scripts/set-powerplan.ps1 for reading
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 Uploading env vars to c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-da6f-c151-7798-428565d3c98e.ps1
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:23:16 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:23:16 packer: 2019/05/10 20:23:16 Uploading file to 'c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-da6f-c151-7798-428565d3c98e.ps1'
2019/05/10 20:23:18 packer: #< CLIXML
2019/05/10 20:23:20 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:23:20 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:23:20 Uploading file to 'c:/Windows/Temp/script-5cd60dbb-f3d7-6e44-e670-3b694004b1dc.ps1'
2019/05/10 20:23:20 [INFO] 529 bytes written for 'uploadData'
2019/05/10 20:23:20 packer: 2019/05/10 20:23:20 [INFO] 529 bytes written for 'uploadData'
2019/05/10 20:23:22 packer: #< CLIXML
2019/05/10 20:23:24 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:23:24 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:23:24 [INFO] starting remote command: powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-da6f-c151-7798-428565d3c98e.ps1; &'c:/Windows/Temp/script-5cd60dbb-f3d7-6e44-e670-3b694004b1dc.ps1'; exit $LastExitCode }"
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 [INFO] command 'powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-da6f-c151-7798-428565d3c98e.ps1; &'c:/Windows/Temp/script-5cd60dbb-f3d7-6e44-e670-3b694004b1dc.ps1'; exit $LastExitCode }"' exited with code: 0
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:23:26 [INFO] 123 bytes written for 'stdout'
2019/05/10 20:23:26 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:23:26 ui: [0;32m    virtualbox-iso: Set power plan to high performance[0m
2019/05/10 20:23:26 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:23:26 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 [INFO] 123 bytes written for 'stdout'
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:23:26 ui: [0;32m    virtualbox-iso: WARNING: Unable to set power plan to high performance[0m
2019/05/10 20:23:26 ui: [1;32m==> virtualbox-iso: Provisioning with powershell script: ./scripts/docker/disable-windows-defender.ps1[0m
2019/05/10 20:23:26 ui: [0;32m    virtualbox-iso: WARNING: Error: HighPerf is null[0m
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 Opening ./scripts/docker/disable-windows-defender.ps1 for reading
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 Uploading env vars to c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-da6f-c151-7798-428565d3c98e.ps1
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:23:26 [INFO] 116 bytes written for 'uploadData'
2019/05/10 20:23:26 packer: 2019/05/10 20:23:26 Uploading file to 'c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-da6f-c151-7798-428565d3c98e.ps1'
2019/05/10 20:23:29 packer: #< CLIXML
2019/05/10 20:23:30 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:23:30 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:23:30 Uploading file to 'c:/Windows/Temp/script-5cd60dbb-f3d7-6e44-e670-3b694004b1dc.ps1'
2019/05/10 20:23:30 packer: 2019/05/10 20:23:30 [INFO] 49 bytes written for 'uploadData'
2019/05/10 20:23:30 [INFO] 49 bytes written for 'uploadData'
2019/05/10 20:23:33 packer: #< CLIXML
2019/05/10 20:23:34 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:23:35 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:23:35 [INFO] starting remote command: powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-da6f-c151-7798-428565d3c98e.ps1; &'c:/Windows/Temp/script-5cd60dbb-f3d7-6e44-e670-3b694004b1dc.ps1'; exit $LastExitCode }"
2019/05/10 20:23:38 packer: 2019/05/10 20:23:38 [INFO] command 'powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-5cd60dbb-da6f-c151-7798-428565d3c98e.ps1; &'c:/Windows/Temp/script-5cd60dbb-f3d7-6e44-e670-3b694004b1dc.ps1'; exit $LastExitCode }"' exited with code: 0
2019/05/10 20:23:38 packer: 2019/05/10 20:23:38 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:23:38 [INFO] 0 bytes written for 'stdout'
2019/05/10 20:23:38 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:23:38 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:23:38 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:23:38 packer: 2019/05/10 20:23:38 [INFO] 0 bytes written for 'stdout'
2019/05/10 20:23:38 packer: 2019/05/10 20:23:38 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:23:38 packer: 2019/05/10 20:23:38 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:23:38 [INFO] (telemetry) ending powershell
2019/05/10 20:23:38 [INFO] (telemetry) Starting provisioner windows-shell
2019/05/10 20:23:38 ui: [1;32m==> virtualbox-iso: Provisioning with windows-shell...[0m
2019/05/10 20:23:38 ui: [1;32m==> virtualbox-iso: Provisioning with shell script: ./scripts/pin-powershell.bat[0m
2019/05/10 20:23:38 packer: 2019/05/10 20:23:38 Opening ./scripts/pin-powershell.bat for reading
2019/05/10 20:23:38 packer: 2019/05/10 20:23:38 Uploading file to '/tmp/script.bat'
2019/05/10 20:23:38 [INFO] 259 bytes written for 'uploadData'
2019/05/10 20:23:38 packer: 2019/05/10 20:23:38 [INFO] 259 bytes written for 'uploadData'
2019/05/10 20:23:40 packer: #< CLIXML
2019/05/10 20:23:42 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:23:42 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:23:42 [INFO] starting remote command: set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"
2019/05/10 20:23:43 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>rem https://connect.microsoft.com/PowerShell/feedback/details/1609288/pin-to-taskbar-no-longer-working-in-windows-10[0m
2019/05/10 20:23:43 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>copy "A:\WindowsPowerShell.lnk" "C:\Users\vagrant\AppData\Local\Temp\Windows PowerShell.lnk"[0m
2019/05/10 20:23:44 ui: [0;32m    virtualbox-iso:         1 file(s) copied.[0m
2019/05/10 20:23:44 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>A:\PinTo10.exe /PTFOL01:'C:\Users\vagrant\AppData\Local\Temp' /PTFILE01:'Windows PowerShell.lnk'[0m
2019/05/10 20:23:45 packer: 2019/05/10 20:23:45 [INFO] command 'set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"' exited with code: 0
2019/05/10 20:23:45 packer: 2019/05/10 20:23:45 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:23:45 [INFO] 428 bytes written for 'stdout'
2019/05/10 20:23:45 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:23:45 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>exit /b 0[0m
2019/05/10 20:23:45 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:23:45 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:23:45 packer: 2019/05/10 20:23:45 [INFO] 428 bytes written for 'stdout'
2019/05/10 20:23:45 packer: 2019/05/10 20:23:45 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:23:45 packer: 2019/05/10 20:23:45 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:23:45 ui: [1;32m==> virtualbox-iso: Provisioning with shell script: ./scripts/compile-dotnet-assemblies.bat[0m
2019/05/10 20:23:45 packer: 2019/05/10 20:23:45 Opening ./scripts/compile-dotnet-assemblies.bat for reading
2019/05/10 20:23:45 packer: 2019/05/10 20:23:45 Uploading file to '/tmp/script.bat'
2019/05/10 20:23:45 [INFO] 684 bytes written for 'uploadData'
2019/05/10 20:23:45 packer: 2019/05/10 20:23:45 [INFO] 684 bytes written for 'uploadData'
2019/05/10 20:23:47 packer: #< CLIXML
2019/05/10 20:23:49 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:23:49 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:23:49 [INFO] starting remote command: set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"
2019/05/10 20:23:50 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if "AMD64" == "AMD64" goto 64BIT[0m
2019/05/10 20:23:50 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>C:\Windows\microsoft.net\framework\v4.0.30319\ngen.exe update /force /queue  1>NUL[0m
2019/05/10 20:23:50 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>C:\Windows\microsoft.net\framework64\v4.0.30319\ngen.exe update /force /queue  1>NUL[0m
2019/05/10 20:23:50 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>C:\Windows\microsoft.net\framework\v4.0.30319\ngen.exe executequeueditems  1>NUL[0m
2019/05/10 20:24:19 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>C:\Windows\microsoft.net\framework64\v4.0.30319\ngen.exe executequeueditems  1>NUL[0m
2019/05/10 20:25:46 packer: 2019/05/10 20:25:46 [INFO] command 'set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"' exited with code: 0
2019/05/10 20:25:46 packer: 2019/05/10 20:25:46 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:25:46 [INFO] 498 bytes written for 'stdout'
2019/05/10 20:25:46 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:25:46 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:25:46 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:25:46 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>exit 0[0m
2019/05/10 20:25:46 packer: 2019/05/10 20:25:46 [INFO] 498 bytes written for 'stdout'
2019/05/10 20:25:46 packer: 2019/05/10 20:25:46 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:25:46 packer: 2019/05/10 20:25:46 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:25:46 ui: [1;32m==> virtualbox-iso: Provisioning with shell script: ./scripts/set-winrm-automatic.bat[0m
2019/05/10 20:25:46 packer: 2019/05/10 20:25:46 Opening ./scripts/set-winrm-automatic.bat for reading
2019/05/10 20:25:46 packer: 2019/05/10 20:25:46 Uploading file to '/tmp/script.bat'
2019/05/10 20:25:46 packer: 2019/05/10 20:25:46 [INFO] 62 bytes written for 'uploadData'
2019/05/10 20:25:46 [INFO] 62 bytes written for 'uploadData'
2019/05/10 20:25:46 packer: #< CLIXML
2019/05/10 20:25:47 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:25:47 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:25:47 [INFO] starting remote command: set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"
2019/05/10 20:25:47 packer: 2019/05/10 20:25:47 [INFO] command 'set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"' exited with code: 0
2019/05/10 20:25:47 packer: 2019/05/10 20:25:47 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:25:47 [INFO] 168 bytes written for 'stdout'
2019/05/10 20:25:47 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:25:47 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:25:47 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>echo Set WinRM start type to auto[0m
2019/05/10 20:25:47 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:25:47 packer: 2019/05/10 20:25:47 [INFO] 168 bytes written for 'stdout'
2019/05/10 20:25:47 packer: 2019/05/10 20:25:47 [INFO] 0 bytes written for 'stderr'
2019/05/10 20:25:47 packer: 2019/05/10 20:25:47 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:25:47 ui: [0;32m    virtualbox-iso: Set WinRM start type to auto[0m
2019/05/10 20:25:47 ui: [1;32m==> virtualbox-iso: Provisioning with shell script: ./scripts/compact.bat[0m
2019/05/10 20:25:47 packer: 2019/05/10 20:25:47 Opening ./scripts/compact.bat for reading
2019/05/10 20:25:47 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>sc config winrm start= auto[0m
2019/05/10 20:25:47 packer: 2019/05/10 20:25:47 Uploading file to '/tmp/script.bat'
2019/05/10 20:25:47 ui: [0;32m    virtualbox-iso: [SC] ChangeServiceConfig SUCCESS[0m
2019/05/10 20:25:47 [INFO] 1685 bytes written for 'uploadData'
2019/05/10 20:25:47 packer: 2019/05/10 20:25:47 [INFO] 1685 bytes written for 'uploadData'
2019/05/10 20:25:47 packer: #< CLIXML
2019/05/10 20:25:47 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>#< CLIXML
2019/05/10 20:25:47 packer: <Objs Version="1.1.0.1" xmlns="http://schemas.microsoft.com/powershell/2004/04"><Obj S="progress" RefId="0"><TN RefId="0"><T>System.Management.Automation.PSCustomObject</T><T>System.Object</T></TN><MS><I64 N="SourceId">1</I64><PR N="Record"><AV>Preparing modules for first use.</AV><AI>0</AI><Nil /><PI>-1</PI><PC>-1</PC><T>Completed</T><SR>-1</SR><SD> </SD></PR></MS></Obj></Objs>2019/05/10 20:25:47 [INFO] starting remote command: set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"
2019/05/10 20:25:48 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if not exist "C:\Windows\Temp\7z1900-x64.msi" (powershell -Command "(New-Object System.Net.WebClient).DownloadFile('https://www.7-zip.org/a/7z1900-x64.msi', 'C:\Windows\Temp\7z1900-x64.msi')"  0<NUL )[0m
2019/05/10 20:25:48 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>msiexec /qb /i C:\Windows\Temp\7z1900-x64.msi[0m
2019/05/10 20:25:49 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if not exist "C:\Windows\Temp\ultradefrag.zip" (powershell -Command "(New-Object System.Net.WebClient).DownloadFile('https://downloads.sourceforge.net/project/ultradefrag/stable-release/6.1.0/ultradefrag-portable-6.1.0.bin.amd64.zip', 'C:\Windows\Temp\ultradefrag.zip')"  0<NUL )[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: Exception calling "DownloadFile" with "2" argument(s): "The request was aborted: Could not create SSL/TLS secure[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: channel."[0m
2019/05/10 20:25:49 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if not exist "C:\Windows\Temp\ultradefrag-portable-6.1.0.amd64\udefrag.exe" (cmd /c ""C:\Program Files\7-Zip\7z.exe" x C:\Windows\Temp\ultradefrag.zip -oC:\Windows\Temp" )[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: At line:1 char:1[0m
2019/05/10 20:25:49 ui: [0;32m    virtualbox-iso: 7-Zip 19.00 (x64) : Copyright (c) 1999-2018 Igor Pavlov : 2019-02-21[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: + (New-Object System.Net.WebClient).DownloadFile('https://downloads.sou ...[0m
2019/05/10 20:25:49 ui: [0;32m    virtualbox-iso: Scanning the drive for archives:[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: + ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~[0m
2019/05/10 20:25:49 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if not exist "C:\Windows\Temp\SDelete.zip" ([0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso:     + CategoryInfo          : NotSpecified: (:) [], MethodInvocationException[0m
2019/05/10 20:25:49 ui: [0;32m    virtualbox-iso: powershell -Command "(New-Object System.Net.WebClient).DownloadFile('https://download.sysinternals.com/files/SDelete.zip', 'C:\Windows\Temp\SDelete.zip')"  0<NUL[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso:     + FullyQualifiedErrorId : WebException[0m
2019/05/10 20:25:49 ui: [0;32m    virtualbox-iso:  powershell -Command "(New-Object System.Net.WebClient).DownloadFile('https://vagrantboxes.blob.core.windows.net/box/sdelete/v1.6.1/sdelete.exe', 'C:\Windows\Temp\sdelete.exe')"  0<NUL[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso:[0m
2019/05/10 20:25:49 ui: [0;32m    virtualbox-iso: )[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: ERROR: The system cannot find the file specified.[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: C:\Windows\Temp\ultradefrag.zip[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: System ERROR:[0m
2019/05/10 20:25:49 ui error: [1;31m==> virtualbox-iso: The system cannot find the file specified.[0m
2019/05/10 20:25:51 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>if not exist "C:\Windows\Temp\sdelete.exe" (cmd /c ""C:\Program Files\7-Zip\7z.exe" x C:\Windows\Temp\SDelete.zip -oC:\Windows\Temp" )[0m
2019/05/10 20:25:51 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>msiexec /qb /x C:\Windows\Temp\7z1900-x64.msi[0m
2019/05/10 20:25:52 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>net stop wuauserv  1>nul 2>&1[0m
2019/05/10 20:25:52 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>rmdir /S /Q C:\Windows\SoftwareDistribution\Download[0m
2019/05/10 20:25:57 ui error: [1;31m==> virtualbox-iso: System error 1058 has occurred.[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>mkdir C:\Windows\SoftwareDistribution\Download[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>net start wuauserv[0m
2019/05/10 20:25:57 ui error: [1;31m==> virtualbox-iso: The service cannot be started, either because it is disabled or because it has no enabled devices associated with it.[0m
2019/05/10 20:25:57 ui error: [1;31m==> virtualbox-iso: The system cannot find the path specified.[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>cmd /c C:\Windows\Temp\ultradefrag-portable-6.1.0.amd64\udefrag.exe --optimize --repeat C:[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>cmd /c C:\Windows\System32\reg.exe ADD HKCU\Software\Sysinternals\SDelete /v EulaAccepted /t REG_DWORD /d 1 /f[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: The operation completed successfully.[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: C:\Users\vagrant>cmd /c C:\Windows\Temp\sdelete.exe -q -z C:[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: SDelete - Secure Delete v1.61[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: Copyright (C) 1999-2012 Mark Russinovich[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: Sysinternals - www.sysinternals.com[0m
2019/05/10 20:25:57 ui: [0;32m    virtualbox-iso: SDelete is set for 1 pass.[0m
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 [INFO] command 'set "PACKER_BUILDER_TYPE=virtualbox-iso" && set "PACKER_BUILD_NAME=virtualbox-iso" && set "PACKER_HTTP_IP=10.0.2.2" &&  cmd /c "/tmp/script.bat"' exited with code: 0
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:28:08 [INFO] 5677 bytes written for 'stdout'
2019/05/10 20:28:08 [INFO] 771 bytes written for 'stderr'
2019/05/10 20:28:08 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:28:08 [INFO] RPC endpoint: Communicator ended with: 0
2019/05/10 20:28:08 ui: [0;32m    virtualbox-iso: Zeroing free space on C:\: 0%
Cleaning free space on C:\: 1%
Cleaning free space on C:\: 2%
Cleaning free space on C:\: 3%
Cleaning free space on C:\: 4%
Cleaning free space on C:\: 5%
Cleaning free space on C:\: 6%
Cleaning free space on C:\: 7%
Cleaning free space on C:\: 8%
Cleaning free space on C:\: 9%
Cleaning free space on C:\: 10%
Cleaning free space on C:\: 11%
Cleaning free space on C:\: 12%
Cleaning free space on C:\: 13%
Cleaning free space on C:\: 14%
Cleaning free space on C:\: 15%
Cleaning free space on C:\: 16%
Cleaning free space on C:\: 17%
Cleaning free space on C:\: 18%
Cleaning free space on C:\: 19%
Cleaning free space on C:\: 20%
Cleaning free space on C:\: 21%
Cleaning free space on C:\: 22%
Cleaning free space on C:\: 23%
Cleaning free space on C:\: 24%
Cleaning free space on C:\: 25%
Cleaning free space on C:\: 26%
Cleaning free space on C:\: 27%
Cleaning free space on C:\: 28%
Cleaning free space on C:\: 29%
Cleaning free space on C:\: 30%
Cleaning free space on C:\: 31%
Cleaning free space on C:\: 32%
Cleaning free space on C:\: 33%
Cleaning free space on C:\: 34%
Cleaning free space on C:\: 35%
Cleaning free space on C:\: 36%
Cleaning free space on C:\: 37%
Cleaning free space on C:\: 38%
Cleaning free space on C:\: 39%
Cleaning free space on C:\: 40%
Cleaning free space on C:\: 41%
Cleaning free space on C:\: 42%
Cleaning free space on C:\: 43%
Cleaning free space on C:\: 44%
Cleaning free space on C:\: 45%
Cleaning free space on C:\: 46%
Cleaning free space on C:\: 47%
Cleaning free space on C:\: 48%
Cleaning free space on C:\: 49%
Cleaning free space on C:\: 50%
Cleaning free space on C:\: 51%
Cleaning free space on C:\: 52%
Cleaning free space on C:\: 53%
Cleaning free space on C:\: 54%
Cleaning free space on C:\: 55%
Cleaning free space on C:\: 56%
Cleaning free space on C:\: 57%
Cleaning free space on C:\: 58%
Cleaning free space on C:\: 59%
Cleaning free space on C:\: 60%
Cleaning free space on C:\: 61%
Cleaning free space on C:\: 62%
Cleaning free space on C:\: 63%
Cleaning free space on C:\: 64%
Cleaning free space on C:\: 65%
Cleaning free space on C:\: 66%
Cleaning free space on C:\: 67%
Cleaning free space on C:\: 68%
Cleaning free space on C:\: 69%
Cleaning free space on C:\: 70%
Cleaning free space on C:\: 71%
Cleaning free space on C:\: 72%
Cleaning free space on C:\: 73%
Cleaning free space on C:\: 74%
Cleaning free space on C:\: 75%
Cleaning free space on C:\: 76%
Cleaning free space on C:\: 77%
Cleaning free space on C:\: 78%
Cleaning free space on C:\: 79%
Cleaning free space on C:\: 80%
Cleaning free space on C:\: 81%
Cleaning free space on C:\: 82%
Cleaning free space on C:\: 83%
Cleaning free space on C:\: 84%
Cleaning free space on C:\: 85%
Cleaning free space on C:\: 86%
Cleaning free space on C:\: 87%
Cleaning free space on C:\: 88%
Cleaning free space on C:\: 89%
Cleaning free space on C:\: 90%
Cleaning free space on C:\: 91%
Cleaning free space on C:\: 92%
Cleaning free space on C:\: 93%
Cleaning free space on C:\: 94%
Cleaning free space on C:\: 95%
Cleaning free space on C:\: 96%
Cleaning free space on C:\: 97%
Cleaning free space on C:\: 98%
Cleaning free space on C:\: 99%
Cleaning free space on C:\: 100%

Cleaning MFT.../
Cleaning MFT...-
Cleaning MFT...\
Free space cleaned on C:\[0m
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 [INFO] 5677 bytes written for 'stdout'
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 [INFO] 771 bytes written for 'stderr'
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 [INFO] RPC client: Communicator ended with: 0
2019/05/10 20:28:08 [INFO] (telemetry) ending windows-shell
2019/05/10 20:28:08 Error in Ui.Message RPC call: connection is shut down
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 Error in Ui.Message RPC call: connection is shut down
2019/05/10 20:28:08 ui: [1;32m==> virtualbox-iso: Gracefully halting virtual machine...[0m
2019/05/10 20:28:08 ui: [0;32m    virtualbox-iso: 1 drives zapped[0m
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 Executing shutdown command: shutdown /s /t 10 /f /d p:4:1 /c "Packer Shutdown"
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 [INFO] starting remote command: shutdown /s /t 10 /f /d p:4:1 /c "Packer Shutdown"
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 [INFO] command 'shutdown /s /t 10 /f /d p:4:1 /c "Packer Shutdown"' exited with code: 0
2019/05/10 20:28:08 packer: 2019/05/10 20:28:08 Waiting max 5m0s for shutdown to complete
2019/05/10 20:28:21 packer: 2019/05/10 20:28:21 VM shut down.
2019/05/10 20:28:21 ui: [0;32m    virtualbox-iso: Removing floppy drive...[0m
2019/05/10 20:28:21 packer: 2019/05/10 20:28:21 Executing VBoxManage: []string{"storageattach", "windows_10", "--storagectl", "Floppy Controller", "--port", "0", "--device", "0", "--medium", "none"}
2019/05/10 20:28:22 packer: 2019/05/10 20:28:22 stdout:
2019/05/10 20:28:22 packer: 2019/05/10 20:28:22 stderr:
2019/05/10 20:28:22 packer: 2019/05/10 20:28:22 Trying for 10 minutes to remove floppy controller.
2019/05/10 20:28:22 packer: 2019/05/10 20:28:22 Executing VBoxManage: []string{"storagectl", "windows_10", "--name", "Floppy Controller", "--remove"}
2019/05/10 20:28:22 packer: 2019/05/10 20:28:22 stdout:
2019/05/10 20:28:22 packer: 2019/05/10 20:28:22 stderr: VBoxManage: error: The machine 'windows_10' is already locked for a session (or being unlocked)
2019/05/10 20:28:22 packer: VBoxManage: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee nsISupports
2019/05/10 20:28:22 packer: VBoxManage: error: Context: "LockMachine(a->session, LockType_Write)" at line 1048 of file VBoxManageStorageController.cpp
2019/05/10 20:28:22 packer: 2019/05/10 20:28:22 Error removing floppy controller. Retrying.
2019/05/10 20:28:22 packer: 2019/05/10 20:28:22 Retryable error: VBoxManage error: VBoxManage: error: The machine 'windows_10' is already locked for a session (or being unlocked)
2019/05/10 20:28:22 packer: VBoxManage: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee nsISupports
2019/05/10 20:28:22 packer: VBoxManage: error: Context: "LockMachine(a->session, LockType_Write)" at line 1048 of file VBoxManageStorageController.cpp
2019/05/10 20:28:37 packer: 2019/05/10 20:28:37 Executing VBoxManage: []string{"storagectl", "windows_10", "--name", "Floppy Controller", "--remove"}
2019/05/10 20:28:37 packer: 2019/05/10 20:28:37 stdout:
2019/05/10 20:28:37 packer: 2019/05/10 20:28:37 stderr:
2019/05/10 20:28:37 packer: 2019/05/10 20:28:37 Executing VBoxManage: []string{"storageattach", "windows_10", "--storagectl", "IDE Controller", "--port", "0", "--device", "1", "--medium", "none"}
2019/05/10 20:28:37 packer: 2019/05/10 20:28:37 stdout:
2019/05/10 20:28:37 packer: 2019/05/10 20:28:37 stderr:
2019/05/10 20:28:37 packer: 2019/05/10 20:28:37 1 second timeout to ensure VM is really shutdown
2019/05/10 20:28:38 ui: [1;32m==> virtualbox-iso: Preparing to export machine...[0m
2019/05/10 20:28:38 ui: [0;32m    virtualbox-iso: Deleting forwarded port mapping for the communicator (SSH, WinRM, etc) (host port 4335)[0m
2019/05/10 20:28:38 packer: 2019/05/10 20:28:38 Executing VBoxManage: []string{"modifyvm", "windows_10", "--natpf1", "delete", "packercomm"}
2019/05/10 20:28:38 packer: 2019/05/10 20:28:38 stdout:
2019/05/10 20:28:38 packer: 2019/05/10 20:28:38 stderr:
2019/05/10 20:28:38 ui: [1;32m==> virtualbox-iso: Exporting virtual machine...[0m
2019/05/10 20:28:38 ui: [0;32m    virtualbox-iso: Executing: export windows_10 --output output-virtualbox-iso/windows_10.ovf[0m
2019/05/10 20:28:38 packer: 2019/05/10 20:28:38 Executing VBoxManage: []string{"export", "windows_10", "--output", "output-virtualbox-iso/windows_10.ovf"}
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 stdout: Successfully exported 1 machine(s).
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 stderr: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 failed to unlock port lockfile: close tcp 127.0.0.1:4335: use of closed network connection
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 Executing VBoxManage: []string{"storageattach", "windows_10", "--storagectl", "Floppy Controller", "--port", "0", "--device", "0", "--medium", "none"}
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 stdout:
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 stderr: VBoxManage: error: Could not find a controller named 'Floppy Controller'
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 Error unregistering floppy: VBoxManage error: VBoxManage: error: Could not find a controller named 'Floppy Controller'
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 failed to unlock port lockfile: close tcp 127.0.0.1:5980: use of closed network connection
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 Executing VBoxManage: []string{"storageattach", "windows_10", "--storagectl", "IDE Controller", "--port", "0", "--device", "1", "--medium", "none"}
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 stdout:
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 stderr: VBoxManage: error: No storage device attached to device slot 1 on port 0 of controller 'IDE Controller'
2019/05/10 20:36:51 packer: VBoxManage: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component SessionMachine, interface IMachine, callee nsISupports
2019/05/10 20:36:51 packer: VBoxManage: error: Context: "DetachDevice(Bstr(pszCtl).raw(), port, device)" at line 403 of file VBoxManageStorageController.cpp
2019/05/10 20:36:51 ui: [1;32m==> virtualbox-iso: Deregistering and deleting VM...[0m
2019/05/10 20:36:51 packer: 2019/05/10 20:36:51 Executing VBoxManage: []string{"unregistervm", "windows_10", "--delete"}
2019/05/10 20:36:52 packer: 2019/05/10 20:36:52 stdout:
2019/05/10 20:36:52 packer: 2019/05/10 20:36:52 stderr: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%
2019/05/10 20:36:52 packer: 2019/05/10 20:36:52 Deleting floppy disk: /tmp/packer596730789
2019/05/10 20:36:52 [INFO] (telemetry) ending virtualbox-iso
2019/05/10 20:36:52 ui: [1;32m==> virtualbox-iso: Running post-processor: vagrant[0m
2019/05/10 20:36:52 [INFO] (telemetry) Starting post-processor vagrant
2019/05/10 20:36:52 ui: [1;32m==> virtualbox-iso (vagrant): Creating Vagrant box for 'virtualbox' provider[0m
2019/05/10 20:36:52 ui: [0;32m    virtualbox-iso (vagrant): Copying from artifact: output-virtualbox-iso/windows_10-disk001.vmdk[0m
2019/05/10 20:37:13 ui: [0;32m    virtualbox-iso (vagrant): Copying from artifact: output-virtualbox-iso/windows_10.ovf[0m
2019/05/10 20:37:13 ui: [0;32m    virtualbox-iso (vagrant): Renaming the OVF to box.ovf...[0m
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Looking for OVF to rename...
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Looking for OVF in artifact...
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Renaming: '/tmp/packer797986070/windows_10.ovf' => box.ovf
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Looking for OVF for base mac address...
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Looking for OVF in artifact...
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Base mac address: 080027B5576A
2019/05/10 20:37:13 ui: [0;32m    virtualbox-iso (vagrant): Using custom Vagrantfile: vagrantfile-windows_10.template[0m
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Turning dir into box: /tmp/packer797986070 => windows_10_virtualbox.box
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Compressing with gzip compression level: -1
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Skipping directory '/tmp/packer797986070' for box 'windows_10_virtualbox.box'
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Box add: '/tmp/packer797986070/Vagrantfile' to 'windows_10_virtualbox.box'
2019/05/10 20:37:13 ui: [0;32m    virtualbox-iso (vagrant): Compressing: Vagrantfile[0m
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Box add: '/tmp/packer797986070/box.ovf' to 'windows_10_virtualbox.box'
2019/05/10 20:37:13 ui: [0;32m    virtualbox-iso (vagrant): Compressing: box.ovf[0m
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Box add: '/tmp/packer797986070/metadata.json' to 'windows_10_virtualbox.box'
2019/05/10 20:37:13 ui: [0;32m    virtualbox-iso (vagrant): Compressing: metadata.json[0m
2019/05/10 20:37:13 packer: 2019/05/10 20:37:13 Box add: '/tmp/packer797986070/windows_10-disk001.vmdk' to 'windows_10_virtualbox.box'
2019/05/10 20:37:13 ui: [0;32m    virtualbox-iso (vagrant): Compressing: windows_10-disk001.vmdk[0m
2019/05/10 20:37:38 [INFO] (telemetry) ending vagrant
2019/05/10 20:37:38 Deleting original artifact for build 'virtualbox-iso'
2019/05/10 20:37:39 ui: [1;32mBuild 'virtualbox-iso' finished.[0m
2019/05/10 20:37:39 Builds completed. Waiting on interrupt barrier...
2019/05/10 20:37:39 ui:
==> Builds finished. The artifacts of successful builds are:
2019/05/10 20:37:39 machine readable: virtualbox-iso,artifact-count []string{"1"}
2019/05/10 20:37:39 machine readable: virtualbox-iso,artifact []string{"0", "builder-id", "mitchellh.post-processor.vagrant"}
2019/05/10 20:37:39 machine readable: virtualbox-iso,artifact []string{"0", "id", "virtualbox"}
2019/05/10 20:37:39 machine readable: virtualbox-iso,artifact []string{"0", "string", "'virtualbox' provider box: windows_10_virtualbox.box"}
2019/05/10 20:37:39 machine readable: virtualbox-iso,artifact []string{"0", "files-count", "1"}
2019/05/10 20:37:39 machine readable: virtualbox-iso,artifact []string{"0", "file", "0", "windows_10_virtualbox.box"}
2019/05/10 20:37:39 machine readable: virtualbox-iso,artifact []string{"0", "end"}
2019/05/10 20:37:39 ui: --> virtualbox-iso: 'virtualbox' provider box: windows_10_virtualbox.box
2019/05/10 20:37:39 [INFO] (telemetry) Finalizing.
2019/05/10 20:37:40 waiting for all plugin processes to complete...
2019/05/10 20:37:40 /usr/bin/packer: plugin process exited
2019/05/10 20:37:40 /usr/bin/packer: plugin process exited
2019/05/10 20:37:40 /usr/bin/packer: plugin process exited
2019/05/10 20:37:40 [ERR] Error decoding response stream 33: EOF
2019/05/10 20:37:40 /usr/bin/packer: plugin process exited
2019/05/10 20:37:40 /usr/bin/packer: plugin process exited
2019/05/10 20:37:40 /usr/bin/packer: plugin process exited
2019/05/10 20:37:40 /usr/bin/packer: plugin process exited