mitchellh/vagrant — Issue #775: `vagrant ssh --command` possibly interpreting command string argument
Author: fnichol
Created: 2012-03-05 19:33:40+00:00
State: open

I'm writing up a base box exporter using Vagrant **1.0.0.rc2 (0.9.99.2)** on **Mac 10.6.8** and I've run into an issue with the way ssh commands are being passed into vagrant. I've attached a pretty long log of an example, so apologies, but everything should be there.

As always, thanks so much for Vagrant and the tireless efforts of Vagrant core!

## Creating a Vagrant VM

```bash
[~/Projects/vagrant {ruby-1.9.3-p125}]
> mkdir vagrant-cmd && cd vagrant-cmd && vagrant init lucid32
A `Vagrantfile` has been placed in this directory. You are now
ready to `vagrant up` your first virtual environment! Please read
the comments in the Vagrantfile as well as documentation on
`vagrantup.com` for more information on using Vagrant.
[~/Projects/vagrant/vagrant-cmd {ruby-1.9.3-p125}]
> vagrant up
[default] Importing base box 'lucid32'...
[default] Matching MAC address for NAT networking...
[default] Clearing any previously set forwarded ports...
[default] Fixed port collision for 22 => 2222. Now on port 2200.
[default] Forwarding ports...
[default] -- 22 => 2200 (adapter 1)
[default] Creating shared folders metadata...
[default] Clearing any previously set network interfaces...
[default] Booting VM...
[default] Waiting for VM to boot. This can take a few minutes.
[default] VM booted and ready for use!
[default] Setting host name...
[default] Mounting shared folders...
[default] -- v-root: /vagrant
```

## Running `vagrant ssh -c 'sudo apt-get -u upgrade'` (Failing)

```bash
[~/Projects/vagrant/vagrant-cmd {ruby-1.9.3-p125}]
> VAGRANT_LOG=debug vagrant ssh -c 'sudo apt-get -y upgrade'
INFO vagrant: `vagrant` invoked: ["ssh", "-c", "sudo", "apt-get", "-y", "upgrade"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#)
INFO environment: - cwd: /Users/fnichol/Projects/vagrant/vagrant-cmd
INFO environment: Home path: /Users/fnichol/.vagrant.d
INFO plugin: Searching and loading any available plugins...
INFO plugin: Loading plugin: vagrant-butter (0.0.2)
INFO plugin: Loaded 1 plugins.
DEBUG vagrant: Loading environment
INFO environment: Loading configuration...
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Populating proc cache for "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :home = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :root = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (evaluating)
DEBUG loader: Loading from: home (evaluating)
INFO environment: Environment initialized (#)
INFO environment: - cwd: /Users/fnichol/Projects/vagrant/vagrant-cmd
INFO environment: Home path: /Users/fnichol/.vagrant.d
INFO plugin: Searching and loading any available plugins...
DEBUG plugin: Plugin already loaded, not loading again: vagrant-butter
INFO plugin: Loaded 1 plugins.
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully
DEBUG environment: Loading configuration for VM: default
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Set :home = #
DEBUG loader: Set :root = #
DEBUG loader: Set :vm = []
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (cache)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Set :box = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :home = #
DEBUG loader: Set :root = #
DEBUG loader: Set :vm = []
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (cache)
DEBUG loader: Loading from: box (evaluating)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully
INFO cli: CLI: [] "ssh" ["-c", "sudo", "apt-get", "-y", "upgrade"]
DEBUG cli: Invoking command class: Vagrant::Command::SSH ["-c", "sudo", "apt-get", "-y", "upgrade"]
ERROR vagrant: Vagrant experienced an error! Details:
ERROR vagrant: #
ERROR vagrant: An invalid option was specified. The help for this command
is available below.

Usage: vagrant ssh [vm-name] [-c command] [-- extra ssh args]

-c, --command COMMAND Execute an SSH command directly.
-p, --plain Plain mode, leaves authentication up to user.
-h, --help Print this help
ERROR vagrant: /Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/command/base.rb:51:in `rescue in parse_options'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/command/base.rb:37:in `parse_options'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/command/ssh.rb:23:in `execute'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/cli.rb:38:in `execute'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/environment.rb:163:in `cli'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/bin/vagrant:43:in `'
/Applications/Vagrant/bin/../embedded/gems/bin/vagrant:19:in `load'
/Applications/Vagrant/bin/../embedded/gems/bin/vagrant:19:in `

'
INFO interface: error: An invalid option was specified. The help for this command
is available below.

Usage: vagrant ssh [vm-name] [-c command] [-- extra ssh args]

-c, --command COMMAND Execute an SSH command directly.
-p, --plain Plain mode, leaves authentication up to user.
-h, --help Print this help
An invalid option was specified. The help for this command
is available below.

Usage: vagrant ssh [vm-name] [-c command] [-- extra ssh args]

-c, --command COMMAND Execute an SSH command directly.
-p, --plain Plain mode, leaves authentication up to user.
-h, --help Print this help
```

## Running `vagrant ssh -c 'sudo whoami'` (Failing)

```bash
[~/Projects/vagrant/vagrant-cmd {ruby-1.9.3-p125}] [134/602]
> VAGRANT_LOG=debug vagrant ssh --command 'sudo whoami'
INFO vagrant: `vagrant` invoked: ["ssh", "--command", "sudo", "whoami"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#)
INFO environment: - cwd: /Users/fnichol/Projects/vagrant/vagrant-cmd
INFO environment: Home path: /Users/fnichol/.vagrant.d
INFO plugin: Searching and loading any available plugins...
INFO plugin: Loading plugin: vagrant-butter (0.0.2)
INFO plugin: Loaded 1 plugins.
DEBUG vagrant: Loading environment
INFO environment: Loading configuration...
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Populating proc cache for "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :home = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :root = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (evaluating)
DEBUG loader: Loading from: home (evaluating)
INFO environment: Environment initialized (#)
INFO environment: - cwd: /Users/fnichol/Projects/vagrant/vagrant-cmd
INFO environment: Home path: /Users/fnichol/.vagrant.d
INFO plugin: Searching and loading any available plugins...
DEBUG plugin: Plugin already loaded, not loading again: vagrant-butter
INFO plugin: Loaded 1 plugins.
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully
DEBUG environment: Loading configuration for VM: default
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Set :home = #
DEBUG loader: Set :root = #
DEBUG loader: Set :vm = []
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (cache)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Set :box = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :home = #
DEBUG loader: Set :root = #
DEBUG loader: Set :vm = []
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (cache)
DEBUG loader: Loading from: box (evaluating)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully
INFO cli: CLI: [] "ssh" ["--command", "sudo", "whoami"]
DEBUG cli: Invoking command class: Vagrant::Command::SSH ["--command", "sudo", "whoami"]
INFO datastore: Created: /Users/fnichol/Projects/vagrant/vagrant-cmd/.vagrant
INFO virtualbox_base: VBoxManage path: VBoxManage
INFO subprocess: Starting process: ["VBoxManage", "--version"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 4.1.8r75467

DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG virtualbox: Finding driver for VirtualBox version: 4.1.8
INFO virtualbox: Using VirtualBox driver: Vagrant::Driver::VirtualBox_4_1
INFO virtualbox_base: VBoxManage path: VBoxManage
INFO subprocess: Starting process: ["VBoxManage", "showvminfo", "205af3f2-f10d-4695-905b-befb328b2a25"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: vagrant-cmd_1330974241
Guest OS: Ubuntu
UUID: 205af3f2-f10d-4695-905b-befb328b2a25
Config file: /Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/vagrant-cmd_1330974241.vbox
Snapshot folder: /Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Snapshots
Log folder: /Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Logs
Hardware UUID: 205af3f2-f10d-4695-905b-befb328b2a25
Memory size: 256MB
Page Fusion: off
VRAM size: 8MB
CPU exec cap: 100%
HPET: off
Chipset: piix3
Firmware: BIOS
Number of CPUs: 1
Synthetic Cpu: off
CPUID overrides: None
Boot menu mode: message and menu
Boot Device (1): HardDisk
Boot Device (2): DVD
Boot Device (3): Not Assigned
Boot Device (4): Not Assigned
ACPI: on
IOAPIC: off
PAE: on
Time offset: 0 ms
RTC: local time
Hardw. virt.ext: on
Hardw. virt.ext exclusive: off
Nested Paging: on
Large Pages: off
VT-x VPID: on
State: running (since 2012-03-05T19:04:15.524000000)
Monitor count: 1
3D Acceleration: off
2D Video Acceleration: off
Teleporter Enabled: off
Teleporter Port: 0
Teleporter Address:
Teleporter Password:
Storage Controller Name (0): IDE Controller
Storage Controller Type (0): PIIX4
Storage Controller Instance Number (0): 0
Storage Controller Max Port Count (0): 2
Storage Controller Port Count (0): 2
Storage Controller Bootable (0): on
Storage Controller Name (1): SATA Controller
Storage Controller Type (1): IntelAhci
Storage Controller Instance Number (1): 0
Storage Controller Max Port Count (1): 30
Storage Controller Port Count (1): 1
Storage Controller Bootable (1): on
IDE Controller (1, 0): Empty
SATA Controller (0, 0): /Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/box-disk1.vmdk (UUID: 255bbe26-45ca-448c-8786-f998e60652ee)
NIC 1: MAC: 080027F18DA7, Attachment: NAT, Cable connected: on, Trace: off (file: none), Type: 82540EM, Reported speed: 0 Mbps, Boot priority: 0, Promisc Policy: deny
NIC 1 Settings: MTU: 0, Socket (send: 64, receive: 64), TCP Window (send:64, receive: 64)
NIC 1 Rule(0): name = ssh, protocol = tcp, host ip = , host port = 2200, guest ip = , guest port = 22
NIC 2: disabled
NIC 3: disabled
NIC 4: disabled
NIC 5: disabled
NIC 6: disabled
NIC 7: disabled
NIC 8: disabled
Pointing Device: PS/2 Mouse
Keyboard Device: PS/2 Keyboard
UART 1: disabled
UART 2: disabled
Audio: disabled
Clipboard Mode: Bidirectional
Video mode: 640x480x0
VRDE: disabled
USB: disabled

USB Device Filters:

Available remote USB devices:

Currently Attached USB Devices:

Shared folders:

Name: 'v-root', Host path: '/Users/fnichol/Projects/vagrant/vagrant-cmd' (machine mapping), writable

VRDE Connection: not active
Clients so far: 0

Guest:

Configured memory balloon size: 0 MB
OS type: Linux26
Additions run level: 2
Additions version: 4.1.8 r75467

Guest Facilities:

Facility "VirtualBox Base Driver": active/running (last update: 2012/03/05 19:04:24 UTC)
Facility "VirtualBox System Service": active/running (last update: 2012/03/05 19:04:27 UTC)
Facility "Seamless Mode": not active (last update: 2012/03/05 19:04:24 UTC)
Facility "Graphics Mode": not active (last update: 2012/03/05 19:04:24 UTC)

DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO vm: Loading guest: linux
ERROR vagrant: Vagrant experienced an error! Details:
ERROR vagrant: #
ERROR vagrant: A multi-vm environment is required for name specification to this command.
ERROR vagrant: /Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/command/base.rb:73:in `with_target_vms'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/command/ssh.rb:39:in `execute'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/cli.rb:38:in `execute'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/lib/vagrant/environment.rb:163:in `cli'
/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/bin/vagrant:43:in `'
/Applications/Vagrant/bin/../embedded/gems/bin/vagrant:19:in `load'
/Applications/Vagrant/bin/../embedded/gems/bin/vagrant:19:in `

'
INFO interface: error: A multi-vm environment is required for name specification to this command.
A multi-vm environment is required for name specification to this command.
```

## Running `vagrant ssh -c 'whoami'` (Passing)

```bash
[~/Projects/vagrant/vagrant-cmd {ruby-1.9.3-p125}]
> VAGRANT_LOG=debug vagrant ssh --command 'whoami'
INFO vagrant: `vagrant` invoked: ["ssh", "--command", "whoami"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#)
INFO environment: - cwd: /Users/fnichol/Projects/vagrant/vagrant-cmd
INFO environment: Home path: /Users/fnichol/.vagrant.d
INFO plugin: Searching and loading any available plugins...
INFO plugin: Loading plugin: vagrant-butter (0.0.2)
INFO plugin: Loaded 1 plugins.
DEBUG vagrant: Loading environment
INFO environment: Loading configuration...
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Populating proc cache for "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :home = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :root = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (evaluating)
DEBUG loader: Loading from: home (evaluating)
INFO environment: Environment initialized (#)
INFO environment: - cwd: /Users/fnichol/Projects/vagrant/vagrant-cmd
INFO environment: Home path: /Users/fnichol/.vagrant.d
INFO plugin: Searching and loading any available plugins...
DEBUG plugin: Plugin already loaded, not loading again: vagrant-butter
INFO plugin: Loaded 1 plugins.
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully
DEBUG environment: Loading configuration for VM: default
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Set :home = #
DEBUG loader: Set :root = #
DEBUG loader: Set :vm = []
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (cache)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully
DEBUG loader: Set :default = "/Applications/Vagrant/embedded/gems/gems/vagrant-0.9.99.2/config/default.rb"
DEBUG loader: Set :box = #
DEBUG loader: Populating proc cache for #
DEBUG loader: Load procs for pathname: #
DEBUG loader: Set :home = #
DEBUG loader: Set :root = #
DEBUG loader: Set :vm = []
DEBUG loader: Loading configuration in order: [:default, :box, :home, :root, :vm]
DEBUG loader: Loading from: default (cache)
DEBUG loader: Loading from: box (evaluating)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully
INFO cli: CLI: [] "ssh" ["--command", "whoami"]
DEBUG cli: Invoking command class: Vagrant::Command::SSH ["--command", "whoami"]
INFO datastore: Created: /Users/fnichol/Projects/vagrant/vagrant-cmd/.vagrant [487/1211]
INFO virtualbox_base: VBoxManage path: VBoxManage
INFO subprocess: Starting process: ["VBoxManage", "--version"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 4.1.8r75467

DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG virtualbox: Finding driver for VirtualBox version: 4.1.8
INFO virtualbox: Using VirtualBox driver: Vagrant::Driver::VirtualBox_4_1
INFO virtualbox_base: VBoxManage path: VBoxManage
INFO subprocess: Starting process: ["VBoxManage", "showvminfo", "205af3f2-f10d-4695-905b-befb328b2a25"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: vagrant-cmd_1330974241
Guest OS: Ubuntu
UUID: 205af3f2-f10d-4695-905b-befb328b2a25
Config file: /Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/vagrant-cmd_1330974241.vbox
Snapshot folder: /Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Snapshots
Log folder: /Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Logs
Hardware UUID: 205af3f2-f10d-4695-905b-befb328b2a25
Memory size: 256MB
Page Fusion: off
VRAM size: 8MB
CPU exec cap: 100%
HPET: off
Chipset: piix3
Firmware: BIOS
Number of CPUs: 1
Synthetic Cpu: off
CPUID overrides: None
Boot menu mode: message and menu
Boot Device (1): HardDisk
Boot Device (2): DVD
Boot Device (3): Not Assigned
Boot Device (4): Not Assigned
ACPI: on
IOAPIC: off
PAE: on
Time offset: 0 ms
RTC: local time
Hardw. virt.ext: on
Hardw. virt.ext exclusive: off
Nested Paging: on
Large Pages: off
VT-x VPID: on
State: running (since 2012-03-05T19:04:15.524000000)
Monitor count: 1
3D Acceleration: off
2D Video Acceleration: off
Teleporter Enabled: off
Teleporter Port: 0
Teleporter Address:
Teleporter Password:
Storage Controller Name (0): IDE Controller
Storage Controller Type (0): PIIX4
Storage Controller Instance Number (0): 0
Storage Controller Max Port Count (0): 2
Storage Controller Port Count (0): 2
Storage Controller Bootable (0): on
Storage Controller Name (1): SATA Controller
Storage Controller Type (1): IntelAhci
Storage Controller Instance Number (1): 0
Storage Controller Max Port Count (1): 30
Storage Controller Port Count (1): 1
Storage Controller Bootable (1): on
IDE Controller (1, 0): Empty
SATA Controller (0, 0): /Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/box-disk1.vmdk (UUID: 255bbe26-45ca-448c-8786-f998e60652ee)
NIC 1: MAC: 080027F18DA7, Attachment: NAT, Cable connected: on, Trace: off (file: none), Type: 82540EM, Reported speed: 0 Mbps, Boot priority: 0, Promisc Policy: deny
NIC 1 Settings: MTU: 0, Socket (send: 64, receive: 64), TCP Window (send:64, receive: 64)
NIC 1 Rule(0): name = ssh, protocol = tcp, host ip = , host port = 2200, guest ip = , guest port = 22
NIC 2: disabled
NIC 3: disabled
NIC 4: disabled
NIC 5: disabled
NIC 6: disabled
NIC 7: disabled
NIC 8: disabled
Pointing Device: PS/2 Mouse
Keyboard Device: PS/2 Keyboard
UART 1: disabled
UART 2: disabled
Audio: disabled
Clipboard Mode: Bidirectional
Video mode: 640x480x0
VRDE: disabled
USB: disabled

USB Device Filters:

Available remote USB devices:

Currently Attached USB Devices:

Shared folders:

Name: 'v-root', Host path: '/Users/fnichol/Projects/vagrant/vagrant-cmd' (machine mapping), writable

VRDE Connection: not active
Clients so far: 0

Guest:

Configured memory balloon size: 0 MB
OS type: Linux26
Additions run level: 2
Additions version: 4.1.8 r75467

Guest Facilities:

Facility "VirtualBox Base Driver": active/running (last update: 2012/03/05 19:04:24 UTC)
Facility "VirtualBox System Service": active/running (last update: 2012/03/05 19:04:27 UTC)
Facility "Seamless Mode": not active (last update: 2012/03/05 19:04:24 UTC)
Facility "Graphics Mode": not active (last update: 2012/03/05 19:04:24 UTC)

DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO vm: Loading guest: linux
INFO subprocess: Starting process: ["VBoxManage", "showvminfo", "205af3f2-f10d-4695-905b-befb328b2a25", "--machinereadable"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: name="vagrant-cmd_1330974241"
ostype="Ubuntu"
UUID="205af3f2-f10d-4695-905b-befb328b2a25"
CfgFile="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/vagrant-cmd_1330974241.vbox"
SnapFldr="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Snapshots"
LogFldr="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Logs"
hardwareuuid="205af3f2-f10d-4695-905b-befb328b2a25"
memory=256
pagefusion="off"
vram=8
cpuexecutioncap=100
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="off"
pae="on"
biossystemtimeoffset=0
rtcuseutc="off"
hwvirtex="on"
hwvirtexexcl="off"
nestedpaging="on"
largepages="off"
vtxvpid="on"
VMState="running"
VMStateChangeTime="2012-03-05T19:04:15.524000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
storagecontrollername0="IDE Controller"
storagecontrollertype0="PIIX4"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="2"
storagecontrollerportcount0="2"
storagecontrollerbootable0="on"
storagecontrollername1="SATA Controller"
storagecontrollertype1="IntelAhci"
storagecontrollerinstance1="0"
storagecontrollermaxportcount1="30"
storagecontrollerportcount1="1"
storagecontrollerbootable1="on"
"IDE Controller-0-0"="none"
"IDE Controller-0-1"="none"
"IDE Controller-1-0"="emptydrive"
"IDE Controller-IsEjected"="off"
"IDE Controller-1-1"="none"
"SATA Controller-0-0"="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/box-disk1.vmdk"
"SATA Controller-ImageUUID-0-0"="255bbe26-45ca-448c-8786-f998e60652ee"
natnet1="nat"
macaddress1="080027F18DA7"
cableconnected1="on"
nic1="nat"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="ssh,tcp,,2200,,22"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
audio="none"
clipboard="bidirectional"
VideoMode="640,480,0"
vrde="off"
usb="off"
SharedFolderNameMachineMapping1="v-root"
SharedFolderPathMachineMapping1="/Users/fnichol/Projects/vagrant/vagrant-cmd"
VRDEActiveConnection="off"
VRDEClients=0
GuestMemoryBalloon=0
GuestOSType="Linux26"
GuestAdditionsRunLevel=2
GuestAdditionsVersion="4.1.8 r75467"
GuestAdditionsFacility_VirtualBox Base Driver=50,1330974264175
GuestAdditionsFacility_VirtualBox System Service=50,1330974267835
GuestAdditionsFacility_Seamless Mode=0,1330974264175
GuestAdditionsFacility_Graphics Mode=0,1330974264175

DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["VBoxManage", "showvminfo", "205af3f2-f10d-4695-905b-befb328b2a25", "--machinereadable"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: name="vagrant-cmd_1330974241"
ostype="Ubuntu"
UUID="205af3f2-f10d-4695-905b-befb328b2a25"
CfgFile="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/vagrant-cmd_1330974241.vbox"
SnapFldr="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Snapshots"
LogFldr="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Logs"
hardwareuuid="205af3f2-f10d-4695-905b-befb328b2a25"
memory=256
pagefusion="off"
vram=8
cpuexecutioncap=100
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="off"
pae="on"
biossystemtimeoffset=0
rtcuseutc="off"
hwvirtex="on"
hwvirtexexcl="off"
nestedpaging="on"
largepages="off"
vtxvpid="on"
VMState="running"
VMStateChangeTime="2012-03-05T19:04:15.524000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
storagecontrollername0="IDE Controller"
storagecontrollertype0="PIIX4"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="2"
storagecontrollerportcount0="2"
storagecontrollerbootable0="on"
storagecontrollername1="SATA Controller"
storagecontrollertype1="IntelAhci"
storagecontrollerinstance1="0"
storagecontrollermaxportcount1="30"
storagecontrollerportcount1="1"
storagecontrollerbootable1="on"
"IDE Controller-0-0"="none"
"IDE Controller-0-1"="none"
"IDE Controller-1-0"="emptydrive"
"IDE Controller-IsEjected"="off"
"IDE Controller-1-1"="none"
"SATA Controller-0-0"="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/box-disk1.vmdk"
"SATA Controller-ImageUUID-0-0"="255bbe26-45ca-448c-8786-f998e60652ee"
natnet1="nat"
macaddress1="080027F18DA7"
cableconnected1="on"
nic1="nat"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="ssh,tcp,,2200,,22"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
audio="none"
clipboard="bidirectional"
VideoMode="640,480,0"
vrde="off"
usb="off"
SharedFolderNameMachineMapping1="v-root"
SharedFolderPathMachineMapping1="/Users/fnichol/Projects/vagrant/vagrant-cmd"
VRDEActiveConnection="off"
VRDEClients=0
GuestMemoryBalloon=0
GuestOSType="Linux26"
GuestAdditionsRunLevel=2
GuestAdditionsVersion="4.1.8 r75467"
GuestAdditionsFacility_VirtualBox Base Driver=50,1330974264175
GuestAdditionsFacility_VirtualBox System Service=50,1330974267835
GuestAdditionsFacility_Seamless Mode=0,1330974264175
GuestAdditionsFacility_Graphics Mode=0,1330974264175

DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["VBoxManage", "showvminfo", "205af3f2-f10d-4695-905b-befb328b2a25", "--machinereadable"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: name="vagrant-cmd_1330974241"
ostype="Ubuntu"
UUID="205af3f2-f10d-4695-905b-befb328b2a25"
CfgFile="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/vagrant-cmd_1330974241.vbox"
SnapFldr="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Snapshots"
LogFldr="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Logs"
hardwareuuid="205af3f2-f10d-4695-905b-befb328b2a25"
memory=256
pagefusion="off"
vram=8
cpuexecutioncap=100
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="off"
pae="on"
biossystemtimeoffset=0
rtcuseutc="off"
hwvirtex="on"
hwvirtexexcl="off"
nestedpaging="on"
largepages="off"
vtxvpid="on"
VMState="running"
VMStateChangeTime="2012-03-05T19:04:15.524000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
storagecontrollername0="IDE Controller"
storagecontrollertype0="PIIX4"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="2"
storagecontrollerportcount0="2"
storagecontrollerbootable0="on"
storagecontrollername1="SATA Controller"
storagecontrollertype1="IntelAhci"
storagecontrollerinstance1="0"
storagecontrollermaxportcount1="30"
storagecontrollerportcount1="1"
storagecontrollerbootable1="on"
"IDE Controller-0-0"="none"
"IDE Controller-0-1"="none"
"IDE Controller-1-0"="emptydrive"
"IDE Controller-IsEjected"="off"
"IDE Controller-1-1"="none"
"SATA Controller-0-0"="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/box-disk1.vmdk"
"SATA Controller-ImageUUID-0-0"="255bbe26-45ca-448c-8786-f998e60652ee"
natnet1="nat"
macaddress1="080027F18DA7"
cableconnected1="on"
nic1="nat"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="ssh,tcp,,2200,,22"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
audio="none"
clipboard="bidirectional"
VideoMode="640,480,0"
vrde="off"
usb="off"
SharedFolderNameMachineMapping1="v-root"
SharedFolderPathMachineMapping1="/Users/fnichol/Projects/vagrant/vagrant-cmd"
VRDEActiveConnection="off"
VRDEClients=0
GuestMemoryBalloon=0
GuestOSType="Linux26"
GuestAdditionsRunLevel=2
GuestAdditionsVersion="4.1.8 r75467"
GuestAdditionsFacility_VirtualBox Base Driver=50,1330974264175
GuestAdditionsFacility_VirtualBox System Service=50,1330974267835
GuestAdditionsFacility_Seamless Mode=0,1330974264175
GuestAdditionsFacility_Graphics Mode=0,1330974264175

DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG ssh: Executing command: whoami
DEBUG virtualbox_4_1: Searching for SSH port: 22
DEBUG virtualbox_4_1: read_forward_ports: uuid=205af3f2-f10d-4695-905b-befb328b2a25 active_only=false
INFO subprocess: Starting process: ["VBoxManage", "showvminfo", "205af3f2-f10d-4695-905b-befb328b2a25", "--machinereadable"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: name="vagrant-cmd_1330974241"
ostype="Ubuntu"
UUID="205af3f2-f10d-4695-905b-befb328b2a25"
CfgFile="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/vagrant-cmd_1330974241.vbox"
SnapFldr="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Snapshots"
LogFldr="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/Logs"
hardwareuuid="205af3f2-f10d-4695-905b-befb328b2a25"
memory=256
pagefusion="off"
vram=8
cpuexecutioncap=100
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="off"
pae="on"
biossystemtimeoffset=0
rtcuseutc="off"
hwvirtex="on"
hwvirtexexcl="off"
nestedpaging="on"
largepages="off"
vtxvpid="on"
VMState="running"
VMStateChangeTime="2012-03-05T19:04:15.524000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
storagecontrollername0="IDE Controller"
storagecontrollertype0="PIIX4"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="2"
storagecontrollerportcount0="2"
storagecontrollerbootable0="on"
storagecontrollername1="SATA Controller"
storagecontrollertype1="IntelAhci"
storagecontrollerinstance1="0"
storagecontrollermaxportcount1="30"
storagecontrollerportcount1="1"
storagecontrollerbootable1="on"
"IDE Controller-0-0"="none"
"IDE Controller-0-1"="none"
"IDE Controller-1-0"="emptydrive"
"IDE Controller-IsEjected"="off"
"IDE Controller-1-1"="none"
"SATA Controller-0-0"="/Users/fnichol/VirtualBox VMs/vagrant-cmd_1330974241/box-disk1.vmdk"
"SATA Controller-ImageUUID-0-0"="255bbe26-45ca-448c-8786-f998e60652ee"
natnet1="nat"
macaddress1="080027F18DA7"
cableconnected1="on"
nic1="nat"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="ssh,tcp,,2200,,22"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
audio="none"
clipboard="bidirectional"
VideoMode="640,480,0"
vrde="off"
usb="off"
SharedFolderNameMachineMapping1="v-root"
SharedFolderPathMachineMapping1="/Users/fnichol/Projects/vagrant/vagrant-cmd"
VRDEActiveConnection="off"
VRDEClients=0
GuestMemoryBalloon=0
GuestOSType="Linux26"
GuestAdditionsRunLevel=2
GuestAdditionsVersion="4.1.8 r75467"
GuestAdditionsFacility_VirtualBox Base Driver=50,1330974264175
GuestAdditionsFacility_VirtualBox System Service=50,1330974267835
GuestAdditionsFacility_Seamless Mode=0,1330974264175
GuestAdditionsFacility_Graphics Mode=0,1330974264175

DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31999
DEBUG subprocess: Exit status: 0
DEBUG virtualbox_4_1: - [1, "ssh", 2200, 22]
DEBUG ssh: Checking key permissions: /Users/fnichol/.vagrant.d/insecure_private_key
DEBUG ssh: Checking key permissions: /Users/fnichol/.vagrant.d/insecure_private_key
INFO ssh: Connecting to SSH: 127.0.0.1:2200
INFO ssh: Execute: whoami (sudo=false)
DEBUG ssh: stdout: vagrant

INFO interface: info: vagrant

vagrant
DEBUG ssh: Exit status: 0
```