Hi.
I use vagrant under jenkins and my goal is to store all config/disks/boxes
etc under the jobs workspace (so it won't conflict with any other jobs, or
leave residue if the job is deleted).
I have the following script to start it up:
#http://williamdurand.fr/2012/12/06/installing-vagrant-in-a-restricted-environment/
is a good reference
export VAGRANT_HOME=$WORKSPACE/.vagrant.d;echo "VAGRANT_HOME is:
$VAGRANT_HOME"
export VBOX_USER_HOME=$WORKSPACE/.vbox;echo "VBOX_USER_HOME is:
$VBOX_USER_HOME"
#http://blog.marcus-brinkmann.de/2010/10/04/using-multiple-virtualbox-configurations-in-the-same-session/
:
export VBOX_IPC_SOCKETID=`echo $JOB_NAME|tr / .`;echo "VBOX_IPC_SOCKETID
is: $VBOX_IPC_SOCKETID"
vagrant --version
http_proxy=http://proxy.edb.com:8080 vagrant plugin install vagrant-vbguest
VAGRANT_LOG=info vagrant up
I see vagrant storing data under the jobs workspace:
-bash-4.1$ ls -ltra .v*
.vagrant.d:
total 40
-rwxr-xr-x 1 jenkins jenkins 3 Mar 10 09:53 setup_version
drwxr-xr-x 2 jenkins jenkins 4096 Mar 10 09:53 data
drwxr-xr-x 2 jenkins jenkins 4096 Mar 10 09:53 rgloader
-rw------- 1 jenkins jenkins 1675 Mar 10 09:53 insecure_private_key
drwxr-xr-x 7 jenkins jenkins 4096 Mar 10 09:53 gems
drwxr-xr-x 7 jenkins jenkins 4096 Mar 10 09:53 .
drwxr-xr-x 3 jenkins jenkins 4096 Mar 10 09:55 boxes
drwxr-xr-x 2 jenkins jenkins 4096 Mar 10 09:55 tmp
drwxr-xr-x 19 jenkins jenkins 4096 Mar 14 12:22 ..
-rwxr-xr-x 1 jenkins jenkins 98 Mar 14 12:37 plugins.json
.vagrant:
total 12
drwxr-xr-x 3 jenkins jenkins 4096 Mar 10 09:53 machines
drwxr-xr-x 3 jenkins jenkins 4096 Mar 10 09:53 .
drwxr-xr-x 19 jenkins jenkins 4096 Mar 14 12:22 ..
.vbox:
total 604
-rwxr-xr-x 1 jenkins jenkins 1184 Mar 10 09:53 compreg.dat
-rwxr-xr-x 1 jenkins jenkins 24549 Mar 10 09:53 xpti.dat
-rwxr-xr-x 1 jenkins jenkins 1331 Mar 10 09:53 VBoxSVC.log.4
-rwxr-xr-x 1 jenkins jenkins 1247 Mar 10 09:57 VirtualBox.xml-prev
-rwxr-xr-x 1 jenkins jenkins 249462 Mar 10 10:08 VBoxSVC.log.3
-rwxr-xr-x 1 jenkins jenkins 8891 Mar 13 13:22 VBoxSVC.log.2
drwxr-xr-x 19 jenkins jenkins 4096 Mar 14 12:22 ..
-rw------- 1 jenkins jenkins 1450 Mar 14 12:23 VirtualBox.xml
-rw------- 1 jenkins jenkins 175112 Mar 14 12:32 VBoxSVC.log.1
drwxr-xr-x 2 jenkins jenkins 4096 Mar 14 12:37 .
-rw------- 1 jenkins jenkins 128960 Mar 14 12:37 VBoxSVC.log
-bash-4.1$
BUT, see attached log, the disk is *still* stored under ~/VirtualBox VMs
-bash-4.1$ pwd
/var/lib/jenkins
-bash-4.1$ find VirtualBox\ VMs/
VirtualBox VMs/
VirtualBox VMs/tac-jfr-server_1391420641_1394796138634_89918
VirtualBox
VMs/tac-jfr-server_1391420641_1394796138634_89918/tac-jfr-server_1391420641_1394796138634_89918.vbox-prev
VirtualBox
VMs/tac-jfr-server_1391420641_1394796138634_89918/tac-jfr-server_1391420641_1394796138634_89918.vbox
VirtualBox VMs/tac-jfr-server_1391420641_1394796138634_89918/box-disk1.vmdk
VirtualBox VMs/tac-jfr-server_1391420641_1394796138634_89918/Logs
VirtualBox VMs/tac-jfr-server_1391420641_1394796138634_89918/Logs/VBox.log
VirtualBox VMs/tac-jfr-server_1391420641_1394796138634_89918/Logs/VBox.log.1
-bash-4.1$
which is no good.
What am I missing? Shouldn't setting of VBOX_USER_HOME and VAGRANT_HOME
cater for this?
--
You received this message because you are subscribed to the Google Groups
"Vagrant" group.
To unsubscribe from this group and stop receiving emails from it, send an email
to [email protected].
For more options, visit https://groups.google.com/d/optout.
00:00:00.677 Allocating TCP port VAGRANT_ORACLE_PORT
00:00:00.678 -> Assigned 49226
00:00:00.678 TCP port allocation complete
00:00:02.367 [tac-jfr-srv] $ /bin/sh -e /tmp/hudson8615654521927502814.sh
00:00:02.371 VAGRANT_HOME is:
/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant.d
00:00:02.371 VBOX_USER_HOME is:
/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vbox
00:00:02.373 VBOX_IPC_SOCKETID is: java.tac.jfr.tac-jfr-srv
00:00:02.640 Vagrant 1.4.2
00:00:03.475 Installing the 'vagrant-vbguest' plugin. This can take a few
minutes...
00:00:13.543 Installed the plugin 'vagrant-vbguest (0.10.0)'!
00:00:13.758 INFO global: Vagrant version: 1.4.2
00:00:13.758 INFO global: Ruby version: 2.0.0
00:00:13.758 INFO global: RubyGems version: 2.0.14
00:00:13.758 INFO global: VAGRANT_LOG="info"
00:00:13.759 INFO global:
VAGRANT_INSTALLER_EMBEDDED_DIR="/opt/vagrant/bin/../embedded"
00:00:13.759 INFO global: VAGRANT_INSTALLER_VERSION="2"
00:00:13.759 INFO global:
VAGRANT_HOME="/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant.d"
00:00:13.759 INFO global: VAGRANT_ORACLE_PORT="49226"
00:00:13.759 INFO global: VAGRANT_DETECTED_OS="Linux"
00:00:13.759 INFO global: VAGRANT_INSTALLER_ENV="1"
00:00:13.777 INFO manager: Registered plugin: VirtualBox provider
00:00:13.784 INFO manager: Registered plugin: kernel
00:00:13.785 INFO manager: Registered plugin: kernel
00:00:13.786 INFO manager: Registered plugin: BSD host
00:00:13.787 INFO manager: Registered plugin: Red Hat host
00:00:13.787 INFO manager: Registered plugin: FreeBSD host
00:00:13.788 INFO manager: Registered plugin: Arch host
00:00:13.789 INFO manager: Registered plugin: Windows host
00:00:13.789 INFO manager: Registered plugin: OpenSUSE host
00:00:13.790 INFO manager: Registered plugin: Gentoo host
00:00:13.791 INFO manager: Registered plugin: Linux host
00:00:13.800 INFO manager: Registered plugin: Slackware host
00:00:13.800 INFO manager: Registered plugin: halt command
00:00:13.800 INFO manager: Registered plugin: up command
00:00:13.800 INFO manager: Registered plugin: resume command
00:00:13.800 INFO manager: Registered plugin: destroy command
00:00:13.800 INFO manager: Registered plugin: ssh command
00:00:13.800 INFO manager: Registered plugin: provision command
00:00:13.800 INFO manager: Registered plugin: package command
00:00:13.801 INFO manager: Registered plugin: init command
00:00:13.801 INFO manager: Registered plugin: help command
00:00:13.803 INFO manager: Registered plugin: reload command
00:00:13.803 INFO manager: Registered plugin: status command
00:00:13.803 INFO manager: Registered plugin: ssh-config command
00:00:13.804 INFO manager: Registered plugin: plugin command
00:00:13.805 INFO manager: Registered plugin: suspend command
00:00:13.805 INFO manager: Registered plugin: box command
00:00:13.806 INFO manager: Registered plugin: ssh communicator
00:00:13.807 INFO manager: Registered plugin: RedHat guest
00:00:13.808 INFO manager: Registered plugin: PLD Linux guest
00:00:13.809 INFO manager: Registered plugin: CoreOS guest
00:00:13.809 INFO manager: Registered plugin: FreeBSD guest
00:00:13.810 INFO manager: Registered plugin: Arch guest
00:00:13.811 INFO manager: Registered plugin: OpenBSD guest
00:00:13.812 INFO manager: Registered plugin: Ubuntu guest
00:00:13.812 INFO manager: Registered plugin: Gentoo guest
00:00:13.813 INFO manager: Registered plugin: Linux guest.
00:00:13.814 INFO manager: Registered plugin: Darwin guest
00:00:13.815 INFO manager: Registered plugin: SUSE guest
00:00:13.816 INFO manager: Registered plugin: Fedora guest
00:00:13.816 INFO manager: Registered plugin: Solaris guest.
00:00:13.817 INFO manager: Registered plugin: OmniOS guest.
00:00:13.818 INFO manager: Registered plugin: Solaris 11 guest.
00:00:13.823 INFO manager: Registered plugin: ESXi guest.
00:00:13.829 INFO manager: Registered plugin: Debian guest
00:00:13.830 INFO manager: Registered plugin: NFS synced folders
00:00:13.831 INFO manager: Registered plugin: file
00:00:13.832 INFO manager: Registered plugin: CFEngine Provisioner
00:00:13.832 INFO manager: Registered plugin: chef
00:00:13.833 INFO manager: Registered plugin: ansible
00:00:13.834 INFO manager: Registered plugin: salt
00:00:13.835 INFO manager: Registered plugin: puppet
00:00:13.835 INFO manager: Registered plugin: shell
00:00:13.836 INFO manager: Registered plugin: docker
00:00:13.860 INFO vagrant: `vagrant` invoked: ["up"]
00:00:13.871 INFO environment: Environment initialized
(#<Vagrant::Environment:0x000000023c8be0>)
00:00:13.871 INFO environment: - cwd:
/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv
00:00:13.871 INFO environment: Home path:
/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant.d
00:00:13.873 INFO environment: Local data path:
/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant
00:00:13.874 INFO environment: Loading plugin from JSON: vagrant-vbguest
00:00:13.958 INFO manager: Registered plugin: vagrant-vbguest
00:00:13.958 INFO manager: Registered required plugin: vagrant-vbguest
00:00:13.958 INFO root: Loaded plugin vagrant-vbguest, version 0.10.0
00:00:13.963 INFO environment: Running hook: environment_plugins_loaded
00:00:13.964 INFO runner: Preparing hooks for middleware sequence...
00:00:13.967 INFO runner: 1 hooks defined.
00:00:13.968 INFO runner: Running action:
#<Vagrant::Action::Builder:0x0000000240e730>
00:00:13.971 INFO environment: Running hook: environment_load
00:00:13.978 INFO environment: Initializing config...
00:00:13.982 INFO loader: Set :default =
"/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/config/default.rb"
00:00:13.983 INFO loader: Set :root =
#<Pathname:/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/Vagrantfile>
00:00:13.983 INFO loader: Loading configuration in order: [:default, :home,
:root]
00:00:14.022 INFO hosts: Host class: VagrantPlugins::HostRedHat::Host
00:00:14.024 INFO runner: Preparing hooks for middleware sequence...
00:00:14.026 INFO runner: 1 hooks defined.
00:00:14.026 INFO runner: Running action:
#<Vagrant::Action::Builder:0x00000002311558>
00:00:14.039 INFO cli: CLI: [] "up" ["--"]
00:00:14.057 INFO command: Active machine found with name default. Using
provider: virtualbox
00:00:14.057 INFO environment: Getting machine: default (virtualbox)
00:00:14.057 INFO environment: Uncached load of machine.
00:00:14.059 INFO loader: Set :vm_default = []
00:00:14.059 INFO loader: Loading configuration in order: [:default, :home,
:root, :vm_default]
00:00:14.060 INFO box_collection: Searching for box: oracle (virtualbox) in
/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant.d/boxes/oracle/virtualbox/metadata.json
00:00:14.060 INFO box_collection: Box found: oracle (virtualbox)
00:00:14.064 INFO environment: Box exists with Vagrantfile. Reloading machine
config.
00:00:14.064 INFO loader: Set :box_oracle_virtualbox =
#<Pathname:/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant.d/boxes/oracle/virtualbox/Vagrantfile>
00:00:14.064 INFO loader: Loading configuration in order: [:default,
:box_oracle_virtualbox, :home, :root, :vm_default]
00:00:14.071 INFO machine: Initializing machine: default
00:00:14.071 INFO machine: - Provider:
VagrantPlugins::ProviderVirtualBox::Provider
00:00:14.071 INFO machine: - Box: #<Vagrant::Box:0x00000002247280>
00:00:14.072 INFO machine: - Data dir:
/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant/machines/default/virtualbox
00:00:14.108 INFO base: VBoxManage path: VBoxManage
00:00:14.113 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"--version"]
00:00:14.162 INFO meta: Using VirtualBox driver:
VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3
00:00:14.165 INFO base: VBoxManage path: VBoxManage
00:00:14.180 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "e5fdaf03-07ea-43d3-9f83-e896d900323d"]
00:00:14.436 INFO base: VBoxManage path: VBoxManage
00:00:14.441 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"--version"]
00:00:14.461 INFO meta: Using VirtualBox driver:
VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3
00:00:14.464 INFO base: VBoxManage path: VBoxManage
00:00:14.468 INFO command: With machine: default
(#<VagrantPlugins::ProviderVirtualBox::Provider:0x00000001b11038
@logger=#<Log4r::Logger:0x00000001b10fc0
@fullname="vagrant::provider::virtualbox", @outputters=[], @additive=true,
@name="virtualbox", @path="vagrant::provider",
@parent=#<Log4r::Logger:0x00000001b11830 @fullname="vagrant",
@outputters=[#<Log4r::StderrOutputter:0x0000000192e6a8 @mon_owner=nil,
@mon_count=0, @mon_mutex=#<Mutex:0x0000000192e5b8>, @name="stderr", @level=0,
@formatter=#<Log4r::DefaultFormatter:0x00000001928a50 @depth=7>,
@out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="",
@parent=#<Log4r::RootLogger:0x00000001b116c8 @level=0, @outputters=[]>,
@level=2, @trace=false>, @level=2, @trace=false>, @machine=#<Vagrant::Machine:
default (VagrantPlugins::ProviderVirtualBox::Provider)>,
@driver=#<VagrantPlugins::ProviderVirtualBox::Driver::Meta:0x000000018e8400
@logger=#<Log4r::Logger:0x00000001b2ada8
@fullname="vagrant::provider::virtualbox::meta", @outputters=[],
@additive=true, @name="meta", @path="vagrant::provider::virtualbox",
@parent=#<Log4r::Logger:0x00000001b10fc0
@fullname="vagrant::provider::virtualbox", @outputters=[], @additive=true,
@name="virtualbox", @path="vagrant::provider",
@parent=#<Log4r::Logger:0x00000001b11830 @fullname="vagrant",
@outputters=[#<Log4r::StderrOutputter:0x0000000192e6a8 @mon_owner=nil,
@mon_count=0, @mon_mutex=#<Mutex:0x0000000192e5b8>, @name="stderr", @level=0,
@formatter=#<Log4r::DefaultFormatter:0x00000001928a50 @depth=7>,
@out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="",
@parent=#<Log4r::RootLogger:0x00000001b116c8 @level=0, @outputters=[]>,
@level=2, @trace=false>, @level=2, @trace=false>, @level=2, @trace=false>,
@interrupted=false, @vboxmanage_path="VBoxManage", @uuid=nil, @version="4.3.6",
@driver=#<VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3:0x000000023252d8
@logger=#<Log4r::Logger:0x000000023b2f70
@fullname="vagrant::provider::virtualbox_4_3", @outputters=[], @additive=true,
@name="virtualbox_4_3", @path="vagrant::provider",
@parent=#<Log4r::Logger:0x00000001b11830 @fullname="vagrant",
@outputters=[#<Log4r::StderrOutputter:0x0000000192e6a8 @mon_owner=nil,
@mon_count=0, @mon_mutex=#<Mutex:0x0000000192e5b8>, @name="stderr", @level=0,
@formatter=#<Log4r::DefaultFormatter:0x00000001928a50 @depth=7>,
@out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="",
@parent=#<Log4r::RootLogger:0x00000001b116c8 @level=0, @outputters=[]>,
@level=2, @trace=false>, @level=2, @trace=false>, @interrupted=false,
@vboxmanage_path="VBoxManage", @uuid=nil>>>)
00:00:14.538 INFO interface: info: Bringing machine 'default' up with
'virtualbox' provider...
00:00:14.549 Bringing machine 'default' up with 'virtualbox' provider...
00:00:14.550 INFO batch_action: Enabling parallelization by default.
00:00:14.550 INFO batch_action: Disabling parallelization because provider
doesn't support it: virtualbox
00:00:14.550 INFO batch_action: Batch action will parallelize: false
00:00:14.550 INFO batch_action: Starting action:
#<Vagrant::Machine:0x00000001cc4e48> up {:destroy_on_error=>true,
:parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil}
00:00:14.550 INFO machine: Calling action: up on provider VirtualBox
(e5fdaf03-07ea-43d3-9f83-e896d900323d)
00:00:14.574 INFO runner: Preparing hooks for middleware sequence...
00:00:14.574 INFO runner: 1 hooks defined.
00:00:14.574 INFO runner: Running action:
#<Vagrant::Action::Builder:0x000000018edc70>
00:00:14.578 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::CheckVirtualbox:0x0000000242c7f8>
00:00:14.590 INFO base: VBoxManage path: VBoxManage
00:00:14.596 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"--version"]
00:00:14.616 INFO meta: Using VirtualBox driver:
VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3
00:00:14.620 INFO base: VBoxManage path: VBoxManage
00:00:14.637 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"list", "hostonlyifs"]
00:00:14.661 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::Call:0x0000000242c7a8>
00:00:14.663 INFO runner: Preparing hooks for middleware sequence...
00:00:14.663 INFO runner: 1 hooks defined.
00:00:14.663 INFO runner: Running action:
#<Vagrant::Action::Builder:0x0000000244d728>
00:00:14.667 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::Created:0x00000002446478>
00:00:14.667 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::Created:0x00000002446478>
00:00:14.684 INFO runner: Preparing hooks for middleware sequence...
00:00:14.684 INFO runner: 1 hooks defined.
00:00:14.684 INFO runner: Running action:
#<Vagrant::Action::Warden:0x000000022f8b20>
00:00:14.701 INFO warden: Calling IN action:
#<Proc:0x00000001c18a80@/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/lib/vagrant/action/warden.rb:94
(lambda)>
00:00:14.701 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::HandleBoxUrl:0x000000022f89b8>
00:00:14.702 INFO handle_box_url: Skipping HandleBoxUrl because box is already
available
00:00:14.702 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::HandleBoxUrl:0x000000022f89b8>
00:00:14.702 INFO warden: Calling OUT action:
#<Proc:0x00000001c18a80@/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/lib/vagrant/action/warden.rb:94
(lambda)>
00:00:14.702 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::ConfigValidate:0x0000000242c758>
00:00:14.702 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::Call:0x0000000242c708>
00:00:14.705 INFO runner: Preparing hooks for middleware sequence...
00:00:14.705 INFO runner: 1 hooks defined.
00:00:14.705 INFO runner: Running action:
#<Vagrant::Action::Builder:0x00000001b35500>
00:00:14.708 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::Created:0x00000001b3cee0>
00:00:14.708 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::Created:0x00000001b3cee0>
00:00:14.722 INFO runner: Preparing hooks for middleware sequence...
00:00:14.722 INFO runner: 1 hooks defined.
00:00:14.723 INFO runner: Running action:
#<Vagrant::Action::Warden:0x000000022295f0>
00:00:14.724 INFO warden: Calling IN action:
#<Proc:0x0000000233f6b0@/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/lib/vagrant/action/warden.rb:94
(lambda)>
00:00:14.725 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::CheckAccessible:0x00000002229320>
00:00:14.725 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::Customize:0x000000022292f8>
00:00:14.725 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::Import:0x000000022292a8>
00:00:14.725 INFO interface: info: Importing base box 'oracle'...
00:00:14.727 [default] Importing base box 'oracle'...
00:00:14.729 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"import", "-n",
"/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant.d/boxes/oracle/virtualbox/box.ovf"]
00:00:14.758 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"import",
"/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant.d/boxes/oracle/virtualbox/box.ovf",
"--vsys", "0", "--vmname", "tac-jfr-server_1391420641_1394796138634_89918",
"--vsys", "0", "--unit", "9", "--disk", "/var/lib/jenkins/VirtualBox
VMs/tac-jfr-server_1391420641_1394796138634_89918/box-disk1.vmdk"]
00:00:47.737 INFO interface: info:
00:00:47.741
INFO interface: info: Progress: 10%
00:00:47.798 Progress: 10% INFO interface: info:
00:00:48.042
INFO interface: info: Progress: 40%
00:00:48.044 Progress: 40% INFO interface: info:
00:00:48.747
INFO interface: info: Progress: 50%
00:00:48.747 Progress: 50% INFO interface: info:
00:00:52.066
INFO interface: info: Progress: 60%
00:00:52.066 Progress: 60% INFO interface: info:
00:00:55.185
INFO interface: info: Progress: 70%
00:00:55.185 Progress: 70% INFO interface: info:
00:00:57.122
INFO interface: info: Progress: 80%
00:00:57.125 Progress: 80% INFO interface: info:
00:00:58.552
INFO interface: info: Progress: 90%
00:00:58.552 Progress: 90% INFO subprocess: Starting process:
["/usr/bin/VBoxManage", "list", "vms"]
00:00:59.801 INFO machine: New machine ID:
"659a4bbd-1de9-4dd7-bc19-7c7f833d8255"
00:00:59.821 INFO base: VBoxManage path: VBoxManage
00:00:59.821 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"--version"]
00:00:59.845 INFO meta: Using VirtualBox driver:
VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3
00:00:59.848 INFO base: VBoxManage path: VBoxManage
00:00:59.853 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255"]
00:00:59.898 INFO interface: info:
00:00:59.898
INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::MatchMACAddress:0x00000002229258>
00:00:59.898 INFO interface: info: Matching MAC address for NAT networking...
00:00:59.899 [default] Matching MAC address for NAT networking...
00:00:59.902 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"modifyvm", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--macaddress1",
"080027880CA6"]
00:00:59.944 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::MatchMACAddress:0x00000002229258>
00:00:59.944 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::Import:0x000000022292a8>
00:00:59.944 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::Customize:0x000000022292f8>
00:00:59.944 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::CheckAccessible:0x00000002229320>
00:00:59.944 INFO warden: Calling OUT action:
#<Proc:0x0000000233f6b0@/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/lib/vagrant/action/warden.rb:94
(lambda)>
00:00:59.944 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::CheckVirtualbox:0x0000000242c578>
00:00:59.947 INFO base: VBoxManage path: VBoxManage
00:00:59.953 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"--version"]
00:00:59.972 INFO meta: Using VirtualBox driver:
VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3
00:00:59.975 INFO base: VBoxManage path: VBoxManage
00:00:59.981 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"list", "hostonlyifs"]
00:01:00.005 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::ConfigValidate:0x0000000242c550>
00:01:00.005 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::Call:0x0000000242c528>
00:01:00.007 INFO runner: Preparing hooks for middleware sequence...
00:01:00.007 INFO runner: 1 hooks defined.
00:01:00.007 INFO runner: Running action:
#<Vagrant::Action::Builder:0x007f6300506828>
00:01:00.010 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::IsRunning:0x007f6300504b40>
00:01:00.031 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:00.076 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::IsRunning:0x007f6300504b40>
00:01:00.080 INFO runner: Preparing hooks for middleware sequence...
00:01:00.080 INFO runner: 1 hooks defined.
00:01:00.080 INFO runner: Running action:
#<Vagrant::Action::Warden:0x00000001c69c78>
00:01:00.083 INFO warden: Calling IN action:
#<Proc:0x00000002292500@/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/lib/vagrant/action/warden.rb:94
(lambda)>
00:01:00.083 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::Call:0x00000001c699d0>
00:01:00.086 INFO runner: Preparing hooks for middleware sequence...
00:01:00.086 INFO runner: 1 hooks defined.
00:01:00.087 INFO runner: Running action:
#<Vagrant::Action::Builder:0x00000002445870>
00:01:00.089 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::IsSaved:0x0000000244d318>
00:01:00.092 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:00.138 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::IsSaved:0x0000000244d318>
00:01:00.1erVirtualBox::Action::ForwardPorts:0x007f63003ae2c8>
00:01:00.744 INFO interface: info: Forwarding ports...
00:01:00.744 [default] Forwarding ports...
00:01:00.748 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:00.793 INFO interface: info: -- 22 => 2222 (adapter 1)
00:01:00.793 [default] -- 22 => 2222 (adapter 1)
00:01:00.793 INFO interface: info: -- 1521 => 49226 (adapter 1)
00:01:00.793 [default] -- 1521 => 49226 (adapter 1)
00:01:00.797 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"modifyvm", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--natpf1",
"ssh,tcp,127.0.0.1,2222,,22", "--natpf1", "tcp49226,tcp,,49226,,1521"]
00:01:00.823 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::SetHostname:0x007f63003ae2a0>
00:01:00.823 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::SaneDefaults:0x007f63003ae278>
00:01:00.826 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"storagectl", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--name", "SATA
Controller", "--hostiocache", "on"]
00:01:00.856 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"modifyvm", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--rtcuseutc", "on"]
00:01:00.883 INFO sanedefaults: Automatically figuring out whether to
enable/disable NAT DNS proxy...
00:01:00.905 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"modifyvm", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--natdnsproxy1", "on"]
00:01:00.933 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::Customize:0x007f63003f94f8>
00:01:00.933 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::Boot:0x007f63003f94d0>
00:01:00.933 INFO interface: info: Booting VM...
00:01:00.934 [default] Booting VM...
00:01:00.937 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"startvm", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--type", "headless"]
00:01:01.142 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::Customize:0x007f63003f94a8>
00:01:01.142 INFO warden: Calling IN action:
#<Vagrant::Action::Builtin::WaitForCommunicator:0x007f63003f9480>
00:01:01.142 INFO interface: info: Waiting for machine to boot. This may take
a few minutes...
00:01:01.154 [default] Waiting for machine to boot. This may take a few
minutes...
00:01:01.156 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:01.392 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:01.470 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:01.585 INFO ssh: Attempting SSH connnection...
00:01:01.586 INFO ssh: Attempting to connect to SSH...
00:01:01.588 INFO ssh: - Host: 127.0.0.1
00:01:01.588 INFO ssh: - Port: 2222
00:01:01.588 INFO ssh: - Username: vagrant
00:01:01.588 INFO ssh: - Key Path:
["/data/jenkins/workspace/java/tac/jfr/tac-jfr-srv/.vagrant.d/insecure_private_key"]
00:01:02.251 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:03.305 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:04.363 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:05.447 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:06.534 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:01:07.657 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--mirectory
00:01:42.114 INFO interface: info: Fetched 72.6 kB in 0s (107 kB/s)
00:01:42.114
00:01:42.116 Fetched 72.6 kB in 0s (107 kB/s)
00:01:42.554 INFO interface: info: Selecting previously unselected package
dkms.
00:01:42.554 (Reading database ...
00:01:42.557 Selecting previously unselected package dkms.
00:01:42.557 (Reading database ... INFO interface: info: 57143 files and
directories currently installed.)
00:01:42.755
00:01:42.758 57143 files and directories currently installed.)
00:01:42.768 INFO interface: info: Unpacking dkms (from
.../dkms_2.2.0.3-1ubuntu3.2_all.deb) ...
00:01:42.768
00:01:42.770 Unpacking dkms (from .../dkms_2.2.0.3-1ubuntu3.2_all.deb) ...
00:01:43.756 INFO interface: info: Processing triggers for man-db ...
00:01:43.756
00:01:43.760 Processing triggers for man-db ...
00:01:47.943 INFO interface: info: Setting up dkms (2.2.0.3-1ubuntu3.2) ...
00:01:47.943
00:01:47.947 Setting up dkms (2.2.0.3-1ubuntu3.2) ...
00:01:49.754 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"list", "dvds"]
00:01:49.806 INFO interface: info: Copy iso file
/usr/share/virtualbox/VBoxGuestAdditions.iso into the box
/tmp/VBoxGuestAdditions.iso
00:01:49.807 Copy iso file /usr/share/virtualbox/VBoxGuestAdditions.iso into
the box /tmp/VBoxGuestAdditions.iso
00:02:29.167 INFO ssh: Execute: mount /tmp/VBoxGuestAdditions.iso -o loop /mnt
(sudo=true)
00:02:29.182 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:02:29.308 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:02:29.401 INFO interface: info: stdin: is not a tty
00:02:29.401
00:02:29.402 stdin: is not a tty
00:02:29.514 INFO interface: info: mount: block device
/tmp/VBoxGuestAdditions.iso is write-protected, mounting read-only
00:02:29.514
00:02:29.516 mount: block device /tmp/VBoxGuestAdditions.iso is
write-protected, mounting read-only
00:02:29.670 INFO ssh: Execute: /mnt/VBoxLinuxAdditions.run --info (sudo=true)
00:02:29.688 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:02:29.805 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:02:29.927 INFO interface: warn: Installing Virtualbox Guest Additions 4.3.6
- guest version is 4.2.0
00:02:29.928 Installing Virtualbox Guest Additions 4.3.6 - guest version is
4.2.0
00:02:30.002 INFO ssh: Execute: /mnt/VBoxLinuxAdditions.run --nox11 (sudo=true)
00:02:30.019 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:02:30.138 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:02:30.224 INFO interface: info: stdin: is not a tty
00:02:30.224
00:02:30.224 stdin: is not a tty
00:02:30.273 INFO interface: info: Verifying archive integrity...
00:02:30.276 Verifying archive integrity... INFO interface: info: All good.
00:02:36.041
00:02:36.101 All good.
00:02:36.133 INFO interface: info: Uncompressing VirtualBox 4.3.6 Guest
Additions for Linux
00:02:36.135 Uncompressing VirtualBox 4.3.6 Guest Additions for Linux INFO
interface: info: ....
00:02:36.267 .... INFO interface: info: .
00:02:36.391 . INFO interface: info: .
00:02:36.500 . INFO interface: info: .
00:02:36.506 . INFO interface: info: .
00:02:36.509 . INFO interface: info: .
00:02:36.512 . INFO interface: info: .
00:02:36.519 . INFO interface: info: .
00:02:36.530 . INFO interface: info: .
00:02:36.693 . INFO interface: info:
00:02:37.058
00:02:37.060
00:02:37.100 INFO interface: info: VirtualBox Guest Additions installer
00:02:37.100
00:02:37.100 VirtualBox Guest Additions installer
00:02:37.202 INFO interface: info: Removing installed version 4.2.0 of
VirtualBox Guest Additions...
00:02:37.202
00:02:37.205 Remrting process: ["/usr/bin/VBoxManage", "guestproperty", "get",
"659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "/VirtualBox/GuestAdd/Version"]
00:05:55.258 INFO ssh: Execute: VBoxService --version (sudo=true)
00:05:55.270 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:55.392 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:55.592 INFO ssh: Execute: lsmod | grep vboxsf (sudo=true)
00:05:55.608 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:55.725 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:55.859 INFO warden: Calling IN action:
#<VagrantPlugins::ProviderVirtualBox::Action::CheckGuestAdditions:0x007f63003f9408>
00:05:55.863 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"guestproperty", "get", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255",
"/VirtualBox/GuestAdd/Version"]
00:05:55.916 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::CheckGuestAdditions:0x007f63003f9408>
00:05:55.916 INFO warden: Calling OUT action:
#<VagrantVbguest::Middleware:0x007f63003f9458>
00:05:55.916 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::WaitForCommunicator:0x007f63003f9480>
00:05:55.916 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::Customize:0x007f63003f94a8>
00:05:55.917 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::Boot:0x007f63003f94d0>
00:05:55.917 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::Customize:0x007f63003f94f8>
00:05:55.917 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::SaneDefaults:0x007f63003ae278>
00:05:55.917 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::SetHostname:0x007f63003ae2a0>
00:05:55.917 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::ForwardPorts:0x007f63003ae2c8>
00:05:55.921 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:55.979 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::Network:0x007f630032a630>
00:05:55.980 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::ClearNetworkInterfaces:0x007f630032a658>
00:05:55.980 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::PrepareNFSSettings:0x007f63002de6b8>
00:05:55.980 INFO synced_folders: Invoking synced folder enable: virtualbox
00:05:55.980 INFO interface: info: Mounting shared folders...
00:05:55.981 [default] Mounting shared folders...
00:05:55.981 INFO interface: info: -- /vagrant
00:05:55.981 [default] -- /vagrant
00:05:55.986 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:56.052 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:56.156 INFO ssh: SSH is ready!
00:05:56.156 INFO guest: Execute capability: mount_virtualbox_shared_folder
(ubuntu)
00:05:56.218 INFO ssh: SSH is ready!
00:05:56.218 INFO guest: Execute capability: shell_expand_guest_path (ubuntu)
00:05:56.272 INFO ssh: Execute: echo; printf /vagrant (sudo=false)
00:05:56.286 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:56.404 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:56.535 INFO ssh: Execute: mkdir -p /vagrant (sudo=true)
00:05:56.553 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8eadable"]
00:05:56.849 INFO ssh: Execute: mount -t vboxsf -o uid=`id -u
vagrant`,gid=`getent group vagrant | cut -d: -f3` /vagrant /vagrant (sudo=true)
00:05:56.866 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:56.982 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:57.217 INFO ssh: Execute: chown `id -u vagrant`:`getent group vagrant |
cut -d: -f3` /vagrant (sudo=true)
00:05:57.228 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:57.354 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:57.631 INFO ssh: Execute: test -x /sbin/initctl (sudo=false)
00:05:57.644 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:57.760 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:57.888 INFO ssh: Execute: /sbin/initctl emit --no-wait vagrant-mounted
MOUNTPOINT=/vagrant (sudo=true)
00:05:57.900 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:58.053 INFO subprocess: Starting process: ["/usr/bin/VBoxManage",
"showvminfo", "659a4bbd-1de9-4dd7-bc19-7c7f833d8255", "--machinereadable"]
00:05:58.162 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::SyncedFolders:0x007f630028e9d8>
00:05:58.162 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::SyncedFolderCleanup:0x007f630024e9a0>
00:05:58.162 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::PrepareNFSValidIds:0x007f63001fdbe0>
00:05:58.162 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::HandleForwardedPortCollisions:0x000000024bdde8>
00:05:58.162 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::PrepareForwardedPortCollisionParams:0x000000024bdf28>
00:05:58.162 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::EnvSet:0x000000024bdfa0>
00:05:58.163 INFO interface: info: VM already provisioned. Run `vagrant
provision` or use `--provision` to force it
00:05:58.164 [default] VM already provisioned. Run `vagrant provision` or use
`--provision` to force it
00:05:58.168 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::Provision:0x0000000244fa28>
00:05:58.168 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::ClearForwardedPorts:0x0000000244fa78>
00:05:58.168 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::SetName:0x0000000234c298>
00:05:58.168 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::CleanMachineFolder:0x0000000234c360>
00:05:58.168 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::CheckAccessible:0x0000000234c388>
00:05:58.168 INFO warden: Calling OUT action:
#<Proc:0x007f6300446190@/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/lib/vagrant/action/warden.rb:94
(lambda)>
00:05:58.168 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::Call:0x007f630027dfe8>
00:05:58.168 INFO warden: Calling OUT action:
#<Proc:0x007f63002b94d0@/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/lib/vagrant/action/warden.rb:94
(lambda)>
00:05:58.168 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::Call:0x00000001c699d0>
00:05:58.168 INFO warden: Calling OUT action:
#<Proc:0x00000002292500@/opt/vagrant/embedded/gems/gems/vagrant-1.4.2/lib/vagrant/action/warden.rb:94
(lambda)>
00:05:58.168 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::Call:0x0000000242c528>
00:05:58.168 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::ConfigValidate:0x0000000242c550>
00:05:58.168 INFO warden: Calling OUTn::Call:0x0000000242c708>
00:05:58.168 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::ConfigValidate:0x0000000242c758>
00:05:58.168 INFO warden: Calling OUT action:
#<Vagrant::Action::Builtin::Call:0x0000000242c7a8>
00:05:58.168 INFO warden: Calling OUT action:
#<VagrantPlugins::ProviderVirtualBox::Action::CheckVirtualbox:0x0000000242c7f8>
00:05:58.168 INFO environment: Running hook: environment_unload
00:05:58.168 INFO runner: Preparing hooks for middleware sequence...
00:05:58.168 INFO runner: 1 hooks defined.
00:05:58.168 INFO runner: Running action:
#<Vagrant::Action::Builder:0x00000002260960> i