search

hashicorp / vagrant

Vagrant is a tool for building and distributing development environments.
https://www.vagrantup.com
Other
26.27k stars 4.43k forks source link

WinRM with more than 1 shell argument: The command line is too long. #3816

Closed marnovdm closed 10 years ago

marnovdm commented 10 years ago

I'm currently testing Windows provisioning features using Vagrant 1.6.2. On my Linux VMs I use a shell provisioner in combination with shell arguments to set up several things on the host. However with Windows it seems that when I use more than 1 argument, it fails with the message that commandline is too long.

                    node_config.vm.provision :shell do |shell|
                            shell.path = BASE_DIRECTORY + '/provisioners/windows-salt/shell_provision.ps1'
                            shell.args = [ fqdn, vm_ip, vm_tags ]
                    end

When I use shell.args = 'test' it works, but I need to pass several arguments. I already tried combining the arguments (by seperating with | or something) but this is still too long. I tried with a Batch file and a Powershell file and these work the same. Is this a bug in Vagrant or am I doing something wrong?

Full log: INFO global: Vagrant version: 1.6.2 INFO global: Ruby version: 2.0.0 INFO global: RubyGems version: 2.0.14 INFO global: VAGRANT_DEFAULT_PROVIDER="vsphere" INFO global: VAGRANT_EXECUTABLE="/opt/vagrant/bin/../embedded/gems/gems/vagrant-1.6.2/bin/vagrant" INFO global: VAGRANT_LOG="debug" INFO global: VAGRANT_INSTALLER_EMBEDDED_DIR="/opt/vagrant/bin/../embedded" INFO global: VAGRANT_INSTALLER_VERSION="2" INFO global: VAGRANT_DETECTED_OS="Linux" INFO global: VAGRANT_INSTALLER_ENV="1" INFO global: VAGRANT_INTERNAL_BUNDLERIZED="1" INFO global: Plugins: INFO global: - builder = 3.2.2 INFO global: - bundler = 1.6.2 INFO global: - i18n = 0.6.9 INFO global: - mime-types = 2.2 INFO global: - nokogiri = 1.5.10 INFO global: - trollop = 2.0 INFO global: - rbvmomi = 1.6.0 INFO global: - rest-client = 1.6.7 INFO global: - vagrant-login = 1.0.1 INFO global: - vagrant-share = 1.0.1 INFO global: - vagrant-vsphere = 0.8.2 DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/kernel_v1/plugin.rb INFO manager: Registered plugin: kernel DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/synced_folders/smb/plugin.rb INFO manager: Registered plugin: SMB synced folders DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/synced_folders/rsync/plugin.rb INFO manager: Registered plugin: RSync synced folders DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/synced_folders/nfs/plugin.rb INFO manager: Registered plugin: NFS synced folders DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/docker/plugin.rb INFO manager: Registered plugin: docker-provider DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/virtualbox/plugin.rb INFO manager: Registered plugin: VirtualBox provider DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/hyperv/plugin.rb INFO manager: Registered plugin: Hyper-V provider DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/kernel_v2/plugin.rb INFO manager: Registered plugin: kernel DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/package/plugin.rb INFO manager: Registered plugin: package command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/ssh_config/plugin.rb INFO manager: Registered plugin: ssh-config command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/resume/plugin.rb INFO manager: Registered plugin: resume command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/global-status/plugin.rb INFO manager: Registered plugin: global-status command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/destroy/plugin.rb INFO manager: Registered plugin: destroy command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/init/plugin.rb INFO manager: Registered plugin: init command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/provision/plugin.rb INFO manager: Registered plugin: provision command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/reload/plugin.rb INFO manager: Registered plugin: reload command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/status/plugin.rb INFO manager: Registered plugin: status command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/box/plugin.rb INFO manager: Registered plugin: box command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/ssh/plugin.rb INFO manager: Registered plugin: ssh command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/up/plugin.rb INFO manager: Registered plugin: up command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/version/plugin.rb INFO manager: Registered plugin: version command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/halt/plugin.rb INFO manager: Registered plugin: halt command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/rdp/plugin.rb INFO manager: Registered plugin: rdp command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/help/plugin.rb INFO manager: Registered plugin: help command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/plugin/plugin.rb INFO manager: Registered plugin: plugin command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/list-commands/plugin.rb INFO manager: Registered plugin: list-commands command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/suspend/plugin.rb INFO manager: Registered plugin: suspend command DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/pld/plugin.rb INFO manager: Registered plugin: PLD Linux guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/coreos/plugin.rb INFO manager: Registered plugin: CoreOS guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/tinycore/plugin.rb INFO manager: Registered plugin: TinyCore Linux guest. DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/funtoo/plugin.rb INFO manager: Registered plugin: Funtoo guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/windows/plugin.rb INFO manager: Registered plugin: Windows guest. DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/fedora/plugin.rb INFO manager: Registered plugin: Fedora guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/solaris11/plugin.rb INFO manager: Registered plugin: Solaris 11 guest. DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/arch/plugin.rb INFO manager: Registered plugin: Arch guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/redhat/plugin.rb INFO manager: Registered plugin: RedHat guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/ubuntu/plugin.rb INFO manager: Registered plugin: Ubuntu guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/omnios/plugin.rb INFO manager: Registered plugin: OmniOS guest. DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/solaris/plugin.rb INFO manager: Registered plugin: Solaris guest. DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/freebsd/plugin.rb INFO manager: Registered plugin: FreeBSD guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/suse/plugin.rb INFO manager: Registered plugin: SUSE guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/gentoo/plugin.rb INFO manager: Registered plugin: Gentoo guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/debian/plugin.rb INFO manager: Registered plugin: Debian guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/netbsd/plugin.rb INFO manager: Registered plugin: NetBSD guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/mint/plugin.rb INFO manager: Registered plugin: Mint guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/smartos/plugin.rb INFO manager: Registered plugin: SmartOS guest. DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/esxi/plugin.rb INFO manager: Registered plugin: ESXi guest. DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/linux/plugin.rb INFO manager: Registered plugin: Linux guest. DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/openbsd/plugin.rb INFO manager: Registered plugin: OpenBSD guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/darwin/plugin.rb INFO manager: Registered plugin: Darwin guest DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/bsd/plugin.rb INFO manager: Registered plugin: BSD host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/null/plugin.rb INFO manager: Registered plugin: null host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/windows/plugin.rb INFO manager: Registered plugin: Windows host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/arch/plugin.rb INFO manager: Registered plugin: Arch host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/redhat/plugin.rb INFO manager: Registered plugin: Red Hat host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/freebsd/plugin.rb INFO manager: Registered plugin: FreeBSD host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/opensuse/plugin.rb INFO manager: Registered plugin: OpenSUSE host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/gentoo/plugin.rb INFO manager: Registered plugin: Gentoo host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/linux/plugin.rb INFO manager: Registered plugin: Linux host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/darwin/plugin.rb INFO manager: Registered plugin: Mac OS X host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/slackware/plugin.rb INFO manager: Registered plugin: Slackware host DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/communicators/winrm/plugin.rb INFO manager: Registered plugin: winrm communicator DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/communicators/ssh/plugin.rb INFO manager: Registered plugin: ssh communicator DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/cfengine/plugin.rb INFO manager: Registered plugin: CFEngine Provisioner DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/puppet/plugin.rb INFO manager: Registered plugin: puppet DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/file/plugin.rb INFO manager: Registered plugin: file DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/docker/plugin.rb INFO manager: Registered plugin: docker DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/salt/plugin.rb INFO manager: Registered plugin: salt DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/shell/plugin.rb INFO manager: Registered plugin: shell DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/chef/plugin.rb INFO manager: Registered plugin: chef DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/ansible/plugin.rb INFO manager: Registered plugin: ansible INFO global: Loading plugins! INFO manager: Registered plugin: vagrant-login INFO manager: Registered plugin: vagrant-share INFO manager: Registered plugin: vsphere INFO vagrant: vagrant invoked: ["up"] DEBUG vagrant: Creating Vagrant environment INFO environment: Environment initialized (#Vagrant::Environment:0x00000001b160d8) INFO environment: - cwd: /srv/salt/vagrant/projects/windows-dev INFO environment: Home path: /root/.vagrant.d INFO environment: Local data path: /srv/salt/vagrant/projects/windows-dev/.vagrant DEBUG environment: Creating: /srv/salt/vagrant/projects/windows-dev/.vagrant INFO environment: Running hook: environment_plugins_loaded INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x00000002256a28 INFO environment: Running hook: environment_load INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000022c58b0 INFO cli: CLI: [] "up" [] DEBUG cli: Invoking command class: VagrantPlugins::CommandUp::Command [] DEBUG command: 'Up' each target VM... INFO loader: Set :root = #Pathname:/srv/salt/vagrant/projects/windows-dev/Vagrantfile DEBUG loader: Populating proc cache for #Pathname:/srv/salt/vagrant/projects/windows-dev/Vagrantfile DEBUG loader: Load procs for pathname: /srv/salt/vagrant/projects/windows-dev/Vagrantfile INFO loader: Loading configuration in order: [:home, :root] DEBUG loader: Loading from: root (evaluating) Enabling Windows support DEBUG loader: Loading from: root (evaluating) DEBUG loader: Configuration loaded successfully, finalizing and returning DEBUG command: Getting target VMs for command. Arguments: DEBUG command: -- names: ["win2008r2.apm.dev"] DEBUG command: -- options: {:provider=>nil} DEBUG command: Finding machine that match name: win2008r2.apm.dev INFO environment: Getting machine: win2008r2.apm.dev (vsphere) INFO environment: Uncached load of machine. INFO loader: Set "21516540_machine_win2008r2.apm.dev" = [["2", #Proc:0x00000002a11bf0@/srv/salt/vagrant/provisioners/windows-salt/Vagrantfile:38]] DEBUG loader: Populating proc cache for ["2", #Proc:0x00000002a11bf0@/srv/salt/vagrant/provisioners/windows-salt/Vagrantfile:38] INFO loader: Loading configuration in order: [:home, :root, "21516540_machine_win2008r2.apm.dev"] DEBUG loader: Loading from: root (cache) DEBUG loader: Loading from: root (cache) DEBUG loader: Loading from: 21516540_machine_win2008r2.apm.dev (evaluating) DEBUG provisioner: Provisioner defined: shell DEBUG loader: Configuration loaded successfully, finalizing and returning INFO box_collection: Box not found: dummy (vsphere) INFO machine: Initializing machine: win2008r2.apm.dev INFO machine: - Provider: VagrantPlugins::VSphere::Provider INFO machine: - Box: INFO machine: - Data dir: /srv/salt/vagrant/projects/windows-dev/.vagrant/machines/win2008r2.apm.dev/vsphere DEBUG machine: Eager loading WinRM communicator to avoid GH-3390 INFO winrm: Initializing WinRMCommunicator INFO command: With machine: win2008r2.apm.dev (#<VagrantPlugins::VSphere::Provider:0x00000001d2e4d8 @machine=#<Vagrant::Machine: win2008r2.apm.dev (VagrantPlugins::VSphere::Provider)>, @cap_logger=#<Log4r::Logger:0x00000001d2d7e0 @fullname="vagrant::capability_host::vagrantplugins::vsphere::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrantplugins::vsphere", @parent=#<Log4r::Logger:0x0000000299d930 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001b11da8 @mon_owner=nil, @mon_count=0, @mon_mutex=#, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001b1b358 @depth=7>, @out=#IO:>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000299d818 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @cap_host_chain=[[:vsphere, #<#Class:0x00000001d2e4b0:0x00000001e456c8>]], @cap_args=[#<Vagrant::Machine: win2008r2.apm.dev (VagrantPlugins::VSphere::Provider)>], @cap_caps={:docker=>#<Vagrant::Registry:0x00000001d2dfb0 @items={:public_address=>#Proc:0x000000027be240@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/docker/plugin.rb:54}, @results_cache={}>, :virtualbox=>#<Vagrant::Registry:0x00000001d2de98 @items={:forwarded_ports=>#Proc:0x000000027d7290@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/virtualbox/plugin.rb:27, :nic_mac_addresses=>#Proc:0x000000027d71a0@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/virtualbox/plugin.rb:32, :public_address=>#Proc:0x0000000227d380@/opt/vagrant/embedded/gems/gems/vagrant-share-1.0.1/lib/vagrant-share.rb:39}, @results_cache={}>, :hyperv=>#<Vagrant::Registry:0x00000001d2dcb8 @items={:public_address=>#Proc:0x000000027dd320@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/hyperv/plugin.rb:25}, @results_cache={}>}>) INFO interface: info: Bringing machine 'win2008r2.apm.dev' up with 'vsphere' provider... Bringing machine 'win2008r2.apm.dev' up with 'vsphere' provider... INFO batch_action: Enabling parallelization by default. INFO batch_action: Disabling parallelization because provider doesn't support it: vsphere INFO batch_action: Batch action will parallelize: false INFO batch_action: Starting action: #Vagrant::Machine:0x000000029885f8 up {:destroy_on_error=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil} INFO machine: Calling action: up on provider vSphere (new) DEBUG environment: Attempting to acquire process-lock: machine-action-02ad16078f92767f32fccf7f7d291410 DEBUG environment: Attempting to acquire process-lock: dotlock INFO environment: Acquired process lock: dotlock INFO environment: Released process lock: dotlock INFO environment: Acquired process lock: machine-action-02ad16078f92767f32fccf7f7d291410 INFO host: Autodetecting host type for [#<Vagrant::Environment: /srv/salt/vagrant/projects/windows-dev>] DEBUG host: Trying: arch DEBUG host: Trying: redhat DEBUG host: Trying: freebsd DEBUG host: Trying: opensuse DEBUG host: Trying: gentoo DEBUG host: Trying: darwin DEBUG host: Trying: slackware DEBUG host: Trying: bsd DEBUG host: Trying: null DEBUG host: Trying: windows DEBUG host: Trying: linux INFO host: Detected: linux! INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000022ac4f0 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004700b30 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x00000004700b08 INFO warden: Calling IN action: #Vagrant::Action::Builtin::Call:0x00000004700ae0 INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000047c8900 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::IsCreated:0x000000047ce5f8 INFO machine: Calling action: get_state on provider vSphere (new) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000047e1ce8 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000047efd20 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000047efcf8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetState:0x000000047efcd0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000047efca8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000047efca8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetState:0x000000047efcd0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000047efcf8 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000047efd20 INFO machine: New machine ID: nil INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::IsCreated:0x000000047ce5f8 INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Warden:0x00000002a1fb60 INFO warden: Calling IN action: #<Proc:0x0000000390ddc0@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)> INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::Clone:0x00000002a1f9a8 INFO interface: info: Calling vSphere CloneVM with the following settings: INFO interface: info: ==> win2008r2.apm.dev: Calling vSphere CloneVM with the following settings: ==> win2008r2.apm.dev: Calling vSphere CloneVM with the following settings: INFO interface: info: -- Source VM: Templates/vagrant-windows2008r2-dev INFO interface: info: ==> win2008r2.apm.dev: -- Source VM: Templates/vagrant-windows2008r2-dev ==> win2008r2.apm.dev: -- Source VM: Templates/vagrant-windows2008r2-dev INFO interface: info: -- Name: win2008r2.apm.dev_1400251638998_70407 INFO interface: info: ==> win2008r2.apm.dev: -- Name: win2008r2.apm.dev_1400251638998_70407 ==> win2008r2.apm.dev: -- Name: win2008r2.apm.dev_1400251638998_70407 [gen_keys] Generated key for win2008r2.apm.dev: OK [gen_keys] Finished generating keys. INFO machine: New machine ID: "42227679-5c04-ca28-bc6d-c2ec9def5c2a" INFO interface: info: Waiting for SSH to become available... INFO interface: info: ==> win2008r2.apm.dev: Waiting for SSH to become available... ==> win2008r2.apm.dev: Waiting for SSH to become available... INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000048952e8 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x0000000489f658 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x0000000489f630 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x0000000489f608 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x0000000489f5e0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x0000000489f5e0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x0000000489f608 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x0000000489f630 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x0000000489f658 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x00000004163678 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x0000000416d678 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x0000000416d600 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x0000000416d588 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x0000000416d538 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x0000000416d538 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x0000000416d588 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x0000000416d600 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x0000000416d678 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000046af3e8 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000046aca30 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000046aca08 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000046ac9e0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000046ac9b8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000046ac9b8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000046ac9e0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000046aca08 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000046aca30 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000047bd9b0 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000047c3518 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000047c34f0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000047c34c8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000047c34a0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000047c34a0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000047c34c8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000047c34f0 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000047c3518 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000048b7398 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x00000001d3ac38 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x00000001d3b0c0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000001d3b138 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000001d3b9f8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000001d3b9f8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000001d3b138 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x00000001d3b0c0 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x00000001d3ac38 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000040d7790 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000040dd0c8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000040dd078 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000040dd000 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000040dcf38 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000040dcf38 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000040dd000 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000040dd078 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000040dd0c8 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000046a02f8 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000046a57a8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000046a5780 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000046a5758 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000046a5730 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000046a5730 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000046a5758 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000046a5780 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000046a57a8 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000047b5b20 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000047bb570 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000047bb548 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000047bb520 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000047bb4f8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000047bb4f8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000047bb520 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000047bb548 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000047bb570 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000048a8b90 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000048ae6a8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000048ae680 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000048ae658 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000048ae630 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000048ae630 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000048ae658 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000048ae680 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000048ae6a8 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x00000003ffa5c0 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004004430 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000040043e0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000004004390 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000040042f0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000040042f0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000004004390 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000040043e0 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004004430 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x00000004681f88 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004687a00 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000046879d8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000046879b0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000004687988 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000004687988 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000046879b0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000046879d8 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004687a00 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x00000004798890 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x0000000479e420 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x0000000479e3f8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x0000000479e3d0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x0000000479e3a8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x0000000479e3a8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x0000000479e3d0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x0000000479e3f8 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x0000000479e420 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x00000004890630 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004895fe0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x00000004895fb8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000004895f90 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000004895f68 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000004895f68 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000004895f90 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x00000004895fb8 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004895fe0 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x0000000394cc78 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x00000003965a20 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000039659d0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000003965958 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000039658e0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000039658e0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000003965958 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000039659d0 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x00000003965a20 INFO winrm: WinRM not up: #<VagrantPlugins::CommunicatorWinRM::Errors::WinRMNotReady: The box is not able to report an address for WinRM to connect to yet. WinRM cannot access this Vagrant environment. Please wait for the Vagrant environment to be running and try again.> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x00000004455750 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x0000000446ac90 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x0000000446ac18 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x0000000446aba0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x0000000446ab50 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x0000000446ab50 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x0000000446aba0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x0000000446ac18 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x0000000446ac90 DEBUG provider: Searching for cap: forwarded_ports DEBUG provider: Checking in: vsphere DEBUG winrmshell: initializing WinRMShell DEBUG winrmshell: powershell executing: hostname if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } INFO winrmshell: Attempting to connect to WinRM... INFO winrmshell: - Host: 10.168.150.196 INFO winrmshell: - Port: 5985 INFO winrmshell: - Username: vagrant DEBUG winrmshell: Output: {:data=>[{:stdout=>"WIN-8HRPEL16JE5\r\n"}], :exitcode=>0} INFO winrm: WinRM is ready! INFO interface: info: New virtual machine successfully cloned and started INFO interface: info: ==> win2008r2.apm.dev: New virtual machine successfully cloned and started ==> win2008r2.apm.dev: New virtual machine successfully cloned and started INFO warden: Calling IN action: #<Proc:0x00000002a1f840@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)> INFO warden: Calling IN action: #Vagrant::Action::Builtin::Call:0x00000004700a90 INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000048a7ad8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::IsRunning:0x000000048a52d8 INFO machine: Calling action: get_state on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000048bf9f8 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000048bd428 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000048bd400 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetState:0x000000048bd3b0 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000048bd388 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000048bd388 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetState:0x000000048bd3b0 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000048bd400 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000048bd428 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::IsRunning:0x000000048a52d8 INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Warden:0x00000003ff18f8 INFO warden: Calling IN action: #<Proc:0x0000000409f7a0@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)> INFO warden: Calling IN action: #<Proc:0x00000003ff1790@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)> INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000004700a40 INFO warden: Calling IN action: #Vagrant::Action::Builtin::Provision:0x00000004700a18 INFO provision: Checking provisioner sentinel if we should run... INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::SyncFolders:0x00000004714888 INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000041dbad8 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000041e1d98 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000041e1d20 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000041e1ca8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000041e1c58 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000041e1c58 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000041e1ca8 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000041e1d20 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000041e1d98 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::SyncFolders:0x00000004714888 INFO provision: Writing provisioning sentinel so we don't provision again INFO interface: info: Running provisioner: shell... INFO interface: info: ==> win2008r2.apm.dev: Running provisioner: shell... ==> win2008r2.apm.dev: Running provisioner: shell... INFO environment: Running hook: provisioner_run INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #<Method: Vagrant::Action::Builtin::Provision#run_provisioner> INFO warden: Calling IN action: #<Proc:0x00000004770ae8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)> INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x00000004792800 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004796d88 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x00000004796d60 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000004796d38 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000004796d10 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x00000004796d10 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x00000004796d38 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x00000004796d60 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x00000004796d88 DEBUG provider: Searching for cap: forwarded_ports DEBUG provider: Checking in: vsphere DEBUG winrmshell: initializing WinRMShell DEBUG winrmshell: powershell executing: hostname if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } INFO winrmshell: Attempting to connect to WinRM... INFO winrmshell: - Host: 10.168.150.196 INFO winrmshell: - Port: 5985 INFO winrmshell: - Username: vagrant DEBUG winrmshell: Output: {:data=>[{:stdout=>"WIN-8HRPEL16JE5\r\n"}], :exitcode=>0} INFO winrm: WinRM is ready! INFO guest: Autodetecting host type for [#<Vagrant::Machine: win2008r2.apm.dev (VagrantPlugins::VSphere::Provider)>] DEBUG guest: Trying: mint DEBUG guest: Trying: pld DEBUG guest: Trying: fedora DEBUG guest: Trying: ubuntu DEBUG guest: Trying: suse DEBUG guest: Trying: coreos DEBUG guest: Trying: tinycore DEBUG guest: Trying: funtoo DEBUG guest: Trying: arch DEBUG guest: Trying: redhat DEBUG guest: Trying: omnios DEBUG guest: Trying: gentoo DEBUG guest: Trying: debian DEBUG guest: Trying: windows DEBUG winrmshell: powershell executing: $p = "$Env:SystemRoot" if ((Test-Path $p) -and (get-item $p).PSIsContainer) { exit 0 } exit 1

if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } DEBUG winrmshell: Output: {:data=>[], :exitcode=>0} INFO guest: Detected: windows! DEBUG guest: Searching for cap: wait_for_reboot DEBUG guest: Checking in: windows DEBUG guest: Found cap: wait_for_reboot in windows INFO winrm: Checking whether WinRM is ready... INFO machine: Calling action: get_ssh_info on provider vSphere (42227679-5c04-ca28-bc6d-c2ec9def5c2a) INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000046a8e80 INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000046ae2b8 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000046ae290 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000046ae268 INFO warden: Calling IN action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000046ae240 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::CloseVSphere:0x000000046ae240 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::GetSshInfo:0x000000046ae268 INFO warden: Calling OUT action: #VagrantPlugins::VSphere::Action::ConnectVSphere:0x000000046ae290 INFO warden: Calling OUT action: #Vagrant::Action::Builtin::ConfigValidate:0x000000046ae2b8 DEBUG provider: Searching for cap: forwarded_ports DEBUG provider: Checking in: vsphere DEBUG winrmshell: initializing WinRMShell DEBUG winrmshell: powershell executing: hostname if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } INFO winrmshell: Attempting to connect to WinRM... INFO winrmshell: - Host: 10.168.150.196 INFO winrmshell: - Port: 5985 INFO winrmshell: - Username: vagrant DEBUG winrmshell: Output: {:data=>[{:stdout=>"WIN-8HRPEL16JE5\r\n"}], :exitcode=>0} INFO winrm: WinRM is ready! DEBUG guest: Searching for cap: wait_for_reboot DEBUG guest: Checking in: windows DEBUG guest: Found cap: wait_for_reboot in windows INFO guest: Execute capability: wait_for_reboot #<Vagrant::Machine: win2008r2.apm.dev (VagrantPlugins::VSphere::Provider)> DEBUG winrmshell: powershell executing:

Function to check whether machine is currently shutting down

function ShuttingDown { [string]$sourceCode = @" using System; using System.Runtime.InteropServices;

namespace Vagrant { public static class RemoteManager { private const int SM_SHUTTINGDOWN = 0x2000;

    [DllImport("User32.dll", CharSet = CharSet.Unicode)]
    private static extern int GetSystemMetrics(int Index);

    public static bool Shutdown() {
        return (0 != GetSystemMetrics(SM_SHUTTINGDOWN));
    }
}

} "@ $type = Add-Type -TypeDefinition $sourceCode -PassThru return $type::Shutdown() }

if (ShuttingDown) { exit 1 } else {

See if a reboot is scheduled in the future by trying to schedule a reboot

. shutdown.exe -f -r -t 60

if ($LASTEXITCODE -eq 1190) {

reboot is already pending

exit 2

}

Remove the pending reboot we just created above

if ($LASTEXITCODE -eq 0) { . shutdown.exe -a } }

no reboot in progress or scheduled

exit 0

if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } DEBUG winrmshell: Output: {:data=>[], :exitcode=>0} DEBUG winrmshell: powershell executing: net use if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } DEBUG winrmshell: Output: {:data=>[{:stdout=>"New connections will be remembered.\r\n"}, {:stdout=>"\r\n"}, {:stdout=>"There are no entries in the list.\r\n\r\n"}], :exitcode=>0} INFO winrm: Uploading: /tmp/vagrant-shell20140516-19857-1ugccv6.ps1 to /tmp/vagrant-shell.ps1 DEBUG filemanager: Upload: /tmp/vagrant-shell20140516-19857-1ugccv6.ps1 -> /tmp/vagrant-shell.ps1 DEBUG winrmshell: powershell executing: $dest_file_path = [System.IO.Path]::GetFullPath('/tmp/vagrant-shell.ps1')

      if (Test-Path $dest_file_path) {
        $crypto_provider = new-object -TypeName System.Security.Cryptography.MD5CryptoServiceProvider
        try {
          $file = [System.IO.File]::Open($dest_file_path, [System.IO.Filemode]::Open, [System.IO.FileAccess]::Read)
          $guest_md5 = ([System.BitConverter]::ToString($crypto_provider.ComputeHash($file))).Replace("-","").ToLower()
        }
        finally {
          $file.Dispose()
        }
        if ($guest_md5 -eq '85677810013ab18f842245e784db42d4') {
          exit 0
        }
      }
      exit 1

if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } DEBUG winrmshell: Output: {:data=>[], :exitcode=>1} DEBUG winrmshell: cmd executing: echo %TEMP% DEBUG winrmshell: Output: {:data=>[{:stdout=>"C:\Users\vagrant\AppData\Local\Temp\r\n"}], :exitcode=>0} DEBUG filemanager: Uploading '/tmp/vagrant-shell20140516-19857-1ugccv6.ps1' to temp file 'C:\Users\vagrant\AppData\Local\Temp/winrm-upload-0.5984144241275206' DEBUG winrmshell: cmd executing: echo V3JpdGUtSG9zdCAiSW5zdGFsbGluZyBTYWx0IGFnZW50Li4uIgojJElQPScxMC4xNjguMTUwLjMnCiMkVEFHUz0nZGV2fHZhZ3JhbnR8d2hhdGV2ZXInCiMkSE9TVE5BTUU9J3dpbjIwMDhyMi5hcG0uZGV2JwojJFBST0pFQ1Q9J3dpbmRvd3MtZGV2JwoKJHdlYmNsaWVudCA9IE5ldy1PYmplY3QgU3lzdGVtLk5ldC5XZWJDbGllbnQKJHVybCA9ICJodHRwczovL2RvY3Muc2FsdHN0YWNrLmNvbS9kb3dubG9hZHMvU2FsdC1NaW5pb24tMjAxNC4xLjMtMS1BTUQ2NC1TZXR1cC5leGUiCiRmaWxlID0gIkM6XHRtcFxzYWx0LmV4ZSIKJHdlYmNsaWVudC5Eb3dubG9hZEZpbGUoJHVybCwkZmlsZSkKQzpcdG1wXHNhbHQuZXhlIC9TIC9tYXN0ZXI9c2FsdCAvbWluaW9uLW5hbWU9d2luMjAwOHIyLmFwbS5kZXYKU3RhcnQtU2VydmljZSAtTmFtZSBzYWx0LW1pbmlvbgoKV3JpdGUtSG9zdCAiQ29tcGxldGVkIHN1Y2Nlc2Z1bGx5Igo= >> "C:\Users\vagrant\AppData\Local\Temp/winrm-upload-0.5984144241275206" DEBUG winrmshell: Output: {:data=>[], :exitcode=>0} DEBUG filemanager: Decoding temp file 'C:\Users\vagrant\AppData\Local\Temp/winrm-upload-0.5984144241275206' to '/tmp/vagrant-shell.ps1' DEBUG winrmshell: powershell executing: $tmp_file_path = [System.IO.Path]::GetFullPath('C:\Users\vagrant\AppData\Local\Temp/winrm-upload-0.5984144241275206') $dest_file_path = [System.IO.Path]::GetFullPath('/tmp/vagrant-shell.ps1')

      if (Test-Path $dest_file_path) {
        rm $dest_file_path
      }
      else {
        $dest_dir = ([System.IO.Path]::GetDirectoryName($dest_file_path))
        New-Item -ItemType directory -Force -Path $dest_dir
      }

      $base64_string = Get-Content $tmp_file_path
      $bytes = [System.Convert]::FromBase64String($base64_string) 
      [System.IO.File]::WriteAllBytes($dest_file_path, $bytes)

if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } DEBUG winrmshell: Output: {:data=>[{:stdout=>"\r\n"}, {:stdout=>"\r\n"}, {:stdout=>" Directory: C:\"}, {:stdout=>"\r\n\r\n\r\n"}, {:stdout=>"Mode LastWriteTime Length Name "}, {:stdout=>"\r\n---- ------------- ------ ---- \r\n"}, {:stdout=>"d---- 16-5-2014 16:49 tmp \r\n"}], :exitcode=>0} INFO interface: detail: Running: c:\tmp\vagrant-shell.ps1 INFO interface: detail: win2008r2.apm.dev: Running: c:\tmp\vagrant-shell.ps1 win2008r2.apm.dev: Running: c:\tmp\vagrant-shell.ps1 DEBUG winrmshell: powershell executing: $command = "powershell -executionpolicy bypass -file c:\tmp\vagrant-shell.ps1 "win2008r2.apm.dev" "10.168.150.211" "windows"" $user = 'vagrant' $password = 'vagrant'

$task_name = "WinRM_Elevated_Shell" $out_file = "$env:SystemRoot\Temp\WinRM_Elevated_Shell.log"

if (Test-Path $out_file) { del $out_file }

$task_xml = @' <?xml version="1.0" encoding="UTF-16"?>

{user} Password HighestAvailable IgnoreNew false false true false false true false true true false false false PT2H 4 cmd {arguments}

'@

$bytes = [System.Text.Encoding]::Unicode.GetBytes($command) $encoded_command = [Convert]::ToBase64String($bytes) $arguments = "/c powershell.exe -EncodedCommand $encoded_command > $out_file 2>&1"

$task_xml = $task_xml.Replace("{arguments}", $arguments) $task_xml = $task_xml.Replace("{user}", $user)

$schedule = New-Object -ComObject "Schedule.Service" $schedule.Connect() $task = $schedule.NewTask($null) $task.XmlText = $task_xml $folder = $schedule.GetFolder("\") $folder.RegisterTaskDefinition($task_name, $task, 6, $user, $password, 1, $null) | Out-Null

$registered_task = $folder.GetTask("\$task_name") $registered_task.Run($null) | Out-Null

$timeout = 10 $sec = 0 while ( (!($registered_task.state -eq 4)) -and ($sec -lt $timeout) ) { Start-Sleep -s 1 $sec++ }

Read the entire file, but only write out new lines we haven't seen before

$numLinesRead = 0 do { Start-Sleep -m 100

if (Test-Path $out_file) { $text = (get-content $out_file) $numLines = ($text | Measure-Object -line).lines
$numLinesToRead = $numLines - $numLinesRead

if ($numLinesToRead -gt 0) {
  $text | select -first $numLinesToRead -skip $numLinesRead | ForEach {
    Write-Host "$_"
  }
  $numLinesRead += $numLinesToRead
}

} } while (!($registered_task.state -eq 3))

$exit_code = $registered_task.LastTaskResult [System.Runtime.Interopservices.Marshal]::ReleaseComObject($schedule) | Out-Null exit $exit_code

if ($LASTEXITCODE) { exit $LASTEXITCODE } else { exit 0 } INFO interface: info: The command line is too long. INFO interface: info: ==> win2008r2.apm.dev: The command line is too long. ==> win2008r2.apm.dev: The command line is too long. DEBUG winrmshell: Output: {:data=>[{:stderr=>"The command line is too long.\r\n"}], :exitcode=>1} ERROR warden: Error occurred: An error occurred executing a remote WinRM command.

Shell: powershell Command: powershell -executionpolicy bypass -file c:\tmp\vagrant-shell.ps1 "win2008r2.apm.dev" "10.168.150.211" "windows" Message: Command execution failed with an exit code of 1 INFO warden: Beginning recovery process... INFO warden: Recovery complete. ERROR warden: Error occurred: An error occurred executing a remote WinRM command.

Shell: powershell Command: powershell -executionpolicy bypass -file c:\tmp\vagrant-shell.ps1 "win2008r2.apm.dev" "10.168.150.211" "windows" Message: Command execution failed with an exit code of 1 INFO warden: Beginning recovery process... INFO warden: Calling recover: #Vagrant::Action::Builtin::Call:0x00000004700a90 INFO warden: Beginning recovery process... INFO warden: Recovery complete. INFO warden: Calling recover: #Vagrant::Action::Builtin::Call:0x00000004700ae0 INFO warden: Beginning recovery process... INFO warden: Recovery complete. INFO warden: Recovery complete. An error occurred executing a remote WinRM command.

Shell: powershell Command: powershell -executionpolicy bypass -file c:\tmp\vagrant-shell.ps1 "win2008r2.apm.dev" "10.168.150.211" "windows" Message: Command execution failed with an exit code of 1 ERROR warden: Error occurred: No error message INFO warden: Beginning recovery process... INFO warden: Recovery complete. ERROR warden: Error occurred: No error message INFO warden: Beginning recovery process... INFO warden: Recovery complete. ERROR warden: Error occurred: No error message INFO warden: Beginning recovery process... INFO warden: Recovery complete. INFO warden: Beginning recovery process... INFO warden: Recovery complete. ERROR warden: Error occurred: No error message INFO warden: Beginning recovery process... INFO warden: Recovery complete. INFO warden: Beginning recovery process... INFO warden: Recovery complete. ERROR warden: Error occurred: No error message INFO warden: Beginning recovery process... INFO warden: Recovery complete. INFO warden: Beginning recovery process... INFO warden: Recovery complete. INFO warden: Beginning recovery process... INFO warden: Recovery complete. INFO warden: Beginning recovery process... INFO warden: Recovery complete. INFO environment: Released process lock: machine-action-02ad16078f92767f32fccf7f7d291410 INFO environment: Running hook: environment_unload INFO runner: Preparing hooks for middleware sequence... INFO runner: 1 hooks defined. INFO runner: Running action: #Vagrant::Action::Builder:0x000000041cd8c0 ERROR vagrant: Vagrant experienced an error! Details: ERROR vagrant: # ERROR vagrant: No error message ERROR vagrant: /root/.vagrant.d/gems/gems/vagrant-vsphere-0.8.2/lib/vSphere/action/close_vsphere.rb:20:in rescue in call' /root/.vagrant.d/gems/gems/vagrant-vsphere-0.8.2/lib/vSphere/action/close_vsphere.rb:12:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:95:inblock in finalize_action' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:95:in block in finalize_action' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/builder.rb:116:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/runner.rb:66:in block in run' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/util/busy.rb:19:inbusy' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/runner.rb:66:in run' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/builtin/call.rb:53:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:95:inblock in finalize_action' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:incall' /root/.vagrant.d/gems/gems/vagrant-vsphere-0.8.2/lib/vSphere/action/clone.rb:55:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:95:in block in finalize_action' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/builder.rb:116:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/runner.rb:66:in block in run' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/util/busy.rb:19:inbusy' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/runner.rb:66:in run' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/builtin/call.rb:53:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /root/.vagrant.d/gems/gems/vagrant-vsphere-0.8.2/lib/vSphere/action/connect_vsphere.rb:19:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/builtin/config_validate.rb:25:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:34:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/builder.rb:116:incall' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/runner.rb:66:in block in run' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/util/busy.rb:19:inbusy' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/runner.rb:66:in run' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/machine.rb:196:inaction_raw' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/machine.rb:173:in block in action' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/environment.rb:434:inlock' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/machine.rb:161:in call' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/machine.rb:161:inaction' /opt/vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/batch_action.rb:82:in block (2 levels) in run' /opt/vagrant/embedded/gems/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:incall' /opt/vagrant/embedded/gems/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:in `block in create_with_logging_context' INFO interface: error: No error message No error message INFO interface: Machine: error-exit ["VagrantPlugins::VSphere::Errors::VSphereError", "No error message"]

sneal commented 10 years ago

Try running the provisioner with 'privileged = false'.

The powershell script we wrap commands in needs to be trimmed back, it appears you're bumping into the stupid 8000 char limit.

marnovdm commented 10 years ago

thanks for your quick response, I can confirm that this does resolve the issue. A bit odd, considering the documentation at http://docs.vagrantup.com/v2/provisioning/shell.html mentions specifically:

privileged (boolean) - Specifies whether to execute the shell script as a privileged user or not (sudo). By default this is "true". -->This has no effect for Windows guests.<---

Perhaps the documentation has not been updated to reflect the new features in Vagrant 1.6.2?

Also I don't think I (should) be hitting the 8000 char limit, unless other stuff is also included.. The shell_provision.ps1 file I'm using (obviously just a first test case):

root@salt001:/srv/salt/vagrant/provisioners/windows-salt # cat shell_provision.ps1 Write-Host "Installing Salt agent..." Write-Host $args

$IP='10.168.150.3'

$TAGS='dev|vagrant|whatever'

$HOSTNAME='win2008r2.apm.dev'

$PROJECT='windows-dev'

$webclient = New-Object System.Net.WebClient $url = "https://docs.saltstack.com/downloads/Salt-Minion-2014.1.3-1-AMD64-Setup.exe" $file = "C:\tmp\salt.exe" $webclient.DownloadFile($url,$file) C:\tmp\salt.exe /S /master=salt /minion-name=win2008r2.apm.dev Start-Service -Name salt-minion

Write-Host "Completed succesfully"

Executed by Vagrant as:

Command: powershell -executionpolicy bypass -file c:\tmp\vagrant-shell.ps1 "win2008r2.apm.dev" "10.168.150.211" "windows"

So I don't think this should add up to >8000 chars. But the good news is your solution works & I can now continue deploying this Windows VM :). Thanks again and have a nice weekend.

EDIT: should I close this issue or should it remain open to rectify documentation perhaps?

sneal commented 10 years ago

You're right the docs need to be updated.

When privileged is true for Windows guests the shell provisioner wraps your shell command in a new powershell script which runs the commands through a scheduled task. The length issue is caused by the Vagrant wrapper script, not your script.

powershell -executionpolicy bypass -file c:\tmp\vagrant-shell.ps1 "win2008r2.apm.dev" "10.168.150.211" "windows"

When this gets added to the wrapper script it pushes the length over 8000 chars, which is probably why with only one arg it was working.

marnovdm commented 10 years ago

Thanks sneal for your explanation. I noticed the documentation of Vagrant is also on GitHub (https://github.com/mitchellh/vagrant/blob/master/website/docs/source/v2/provisioning/shell.html.md). I would create a pull request myself, however I still don't fully understand why this is influenced by the privileged parameter (it still run's the script as admin as far as I could see) and not by, for example, a new 'use_wrapper' parameter.

mitchellh commented 10 years ago

@sneal What is the proper course of action here for Vagrant?

StevenArmstrong commented 10 years ago

Hi I am currently getting the same bug with vagrant 1.6.2 booting windows guests on vsphere and the chef provisioner is giving the same command too long error. My vagrant file is: Vagrant.configure("2") do |config|

config.vm.define :TestBox do |config|

config.windows.halt_timeout = 15

config.winrm.username = "Administrator" config.winrm.password = "xxxxxxxx" config.vm.box = "TestBox" config.vm.communicator = "winrm" config.berkshelf.enabled = true

config.vm.network :forwarded_port, guest: 5985, host: 5985, id: "winrm", auto_correct:true

config.vm.box_url = "./TestBox.box" config.vm.guest = :windows

config.vm.provision "chef_solo" do |chef|

chef.add_recipe "windows::TestRunBook"

end

config.vm.provider :vsphere do |vsphere| vsphere.host = 'x.20.1.67' vsphere.compute_resource_name = 'x.20.1.97' vsphere.resource_pool_name = 'Provisioning' vsphere.template_name = 'DevOps_Production_Templates/DevOps_Windows2008R2' vsphere.name = 'TestBox' vsphere.user = 'administrator' vsphere.password = 'xxxxx' vsphere.insecure = 'false' vsphere.data_store_name = 'QA-DS100'

vsphere.customization_spec_name = 'QAProvisioning'

end

end

sneal commented 10 years ago

The easiest thing to do is to shorten the WinRM_Elevated_Shell script, there's some unnecessary XML for creating the scheduled task. Ultimately I think we should upload WinRM_Elevated_Shell and execute it instead of sending it directly to the command processor. I'll send a PR Monday for this.

StevenArmstrong commented 10 years ago

@sneal thanks again, I was hoping to upgrade to vagrant 1.6.2 this week and this is the one thing that is preventing that, as currently chef solo provisioning on windows doesn't work at all in this version as it stands and all my run books are built using that. Thanks for the quick response as usual it is much appreciated.