Skip to content

Instantly share code, notes, and snippets.

@varac
Created August 22, 2014 12:05
Show Gist options
  • Save varac/5a3f35082f8226a03956 to your computer and use it in GitHub Desktop.
Save varac/5a3f35082f8226a03956 to your computer and use it in GitHub Desktop.
Vagrant-cachier works fine on initial 'vagrant up', but not on subsequent tries #119
» VAGRANT_LOG=debug vagrant up && VAGRANT_LOG=debug vagrant reload
INFO global: Vagrant version: 1.6.3
INFO global: Ruby version: 2.0.0
INFO global: RubyGems version: 2.0.14
INFO global: VAGRANT_DEFAULT_PROVIDER="libvirt"
INFO global: VAGRANT_EXECUTABLE="/opt/vagrant/bin/../embedded/gems/gems/vagrant-1.6.3/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: - Platform = 0.4.0
INFO global: - builder = 3.2.2
INFO global: - bundler = 1.6.2
INFO global: - excon = 0.39.4
INFO global: - formatador = 0.2.5
INFO global: - mime-types = 1.25.1
INFO global: - net-ssh = 2.9.1
INFO global: - net-scp = 1.1.2
INFO global: - fog-core = 1.23.0
INFO global: - multi_json = 1.10.1
INFO global: - fog-json = 1.0.0
INFO global: - inflecto = 0.0.2
INFO global: - fog-brightbox = 0.2.0
INFO global: - fog-softlayer = 0.3.13
INFO global: - ipaddress = 0.8.0
INFO global: - nokogiri = 1.5.11
INFO global: - fog = 1.23.0
INFO global: - json = 1.8.1
INFO global: - open4 = 1.3.4
INFO global: - popen4 = 0.1.2
INFO global: - rdoc = 4.1.1
INFO global: - rest-client = 1.6.8
INFO global: - ruby-libvirt = 0.4.0
INFO global: - sahara = 0.0.17
INFO global: - vagrant-cachier = 0.9.0
INFO global: - vagrant-libvirt = 0.0.19
INFO global: - vagrant-login = 1.0.1
INFO global: - vagrant-share = 1.1.0
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/kernel_v1/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/openbsd/plugin.rb
INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/solaris/plugin.rb
INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/debian/plugin.rb
INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/smartos/plugin.rb
INFO manager: Registered plugin: SmartOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/funtoo/plugin.rb
INFO manager: Registered plugin: Funtoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/netbsd/plugin.rb
INFO manager: Registered plugin: NetBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/coreos/plugin.rb
INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/plugins/guests/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/windows/plugin.rb
INFO manager: Registered plugin: Windows guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/mint/plugin.rb
INFO manager: Registered plugin: Mint guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/plugins/guests/esxi/plugin.rb
INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/nixos/plugin.rb
INFO manager: Registered plugin: NixOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/suse/plugin.rb
INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/omnios/plugin.rb
INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/redhat/plugin.rb
INFO manager: Registered plugin: RedHat guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/darwin/plugin.rb
INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/linux/plugin.rb
INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/arch/plugin.rb
INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/ubuntu/plugin.rb
INFO manager: Registered plugin: Ubuntu guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/plugins/guests/fedora/plugin.rb
INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/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.3/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.3/plugins/communicators/winrm/plugin.rb
INFO manager: Registered plugin: winrm communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/communicators/ssh/plugin.rb
INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/null/plugin.rb
INFO manager: Registered plugin: null host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/opensuse/plugin.rb
INFO manager: Registered plugin: OpenSUSE host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/bsd/plugin.rb
INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/windows/plugin.rb
INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/slackware/plugin.rb
INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/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.3/plugins/hosts/linux/plugin.rb
INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/arch/plugin.rb
INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/help/plugin.rb
INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/halt/plugin.rb
INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/rdp/plugin.rb
INFO manager: Registered plugin: rdp command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/status/plugin.rb
INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/suspend/plugin.rb
INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/ssh/plugin.rb
INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/reload/plugin.rb
INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/destroy/plugin.rb
INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/box/plugin.rb
INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/plugin/plugin.rb
INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/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.3/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.3/plugins/commands/provision/plugin.rb
INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/package/plugin.rb
INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/init/plugin.rb
INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/version/plugin.rb
INFO manager: Registered plugin: version command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/up/plugin.rb
INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/resume/plugin.rb
INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/cfengine/plugin.rb
INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/salt/plugin.rb
INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/chef/plugin.rb
INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/puppet/plugin.rb
INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/shell/plugin.rb
INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/ansible/plugin.rb
INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/file/plugin.rb
INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/docker/plugin.rb
INFO manager: Registered plugin: docker
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/kernel_v2/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/virtualbox/plugin.rb
INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/plugins/providers/docker/plugin.rb
INFO manager: Registered plugin: docker-provider
INFO global: Loading plugins!
INFO manager: Registered plugin: vagrant-login
INFO manager: Registered plugin: vagrant-share
INFO manager: Registered plugin: libvirt
INFO manager: Registered plugin: sahara
INFO manager: Registered plugin: vagrant-cachier
INFO vagrant: `vagrant` invoked: ["up"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#<Vagrant::Environment:0x000000028ee250>)
INFO environment: - cwd: /home/varac/vagrant/buildbox2
INFO environment: Home path: /home/varac/.vagrant.d
INFO environment: Local data path: /home/varac/vagrant/buildbox2/.vagrant
DEBUG environment: Creating: /home/varac/vagrant/buildbox2/.vagrant
INFO environment: Running hook: environment_plugins_loaded
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000002a0af30>
INFO environment: Running hook: environment_load
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000201bc18>
INFO cli: CLI: [] "up" []
DEBUG cli: Invoking command class: VagrantPlugins::CommandUp::Command []
DEBUG command: 'Up' each target VM...
INFO loader: Set :home = #<Pathname:/home/varac/.vagrant.d/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:/home/varac/.vagrant.d/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/varac/.vagrant.d/Vagrantfile
INFO loader: Set :root = #<Pathname:/home/varac/vagrant/buildbox2/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:/home/varac/vagrant/buildbox2/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/varac/vagrant/buildbox2/Vagrantfile
INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: home (evaluating)
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: ["buildbox2"]
DEBUG command: -- options: {:provider=>nil}
DEBUG command: Finding machine that match name: buildbox2
INFO environment: Getting machine: buildbox2 (libvirt)
INFO environment: Uncached load of machine.
INFO loader: Set "20768440_machine_buildbox2" = [["2", #<Proc:0x00000002c6c2b0@/home/varac/vagrant/buildbox2/Vagrantfile:2>]]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00000002c6c2b0@/home/varac/vagrant/buildbox2/Vagrantfile:2>]
INFO loader: Loading configuration in order: [:home, :root, "20768440_machine_buildbox2"]
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 20768440_machine_buildbox2 (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO box_collection: Box found: leap-wheezy (libvirt)
INFO loader: Set :"17760020_leap-wheezy_libvirt" = #<Pathname:/home/varac/.vagrant.d/boxes/leap-wheezy/0/libvirt/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:/home/varac/.vagrant.d/boxes/leap-wheezy/0/libvirt/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/varac/.vagrant.d/boxes/leap-wheezy/0/libvirt/Vagrantfile
Vagrant.require_plugin is deprecated and has no effect any longer.
Use `vagrant plugin` commands to manage plugins. This warning will
be removed in the next version of Vagrant.
INFO loader: Loading configuration in order: [:"17760020_leap-wheezy_libvirt", :home, :root, "20768440_machine_buildbox2"]
DEBUG loader: Loading from: 17760020_leap-wheezy_libvirt (evaluating)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 20768440_machine_buildbox2 (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO loader: Set :"20768440_vm_buildbox2_leap-wheezy_libvirt" = [["2", #<Proc:0x000000029133c0>]]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x000000029133c0>]
INFO loader: Loading configuration in order: [:"17760020_leap-wheezy_libvirt", :home, :root, "20768440_machine_buildbox2", :"20768440_vm_buildbox2_leap-wheezy_libvirt"]
DEBUG loader: Loading from: 17760020_leap-wheezy_libvirt (cache)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 20768440_machine_buildbox2 (cache)
DEBUG loader: Loading from: 20768440_vm_buildbox2_leap-wheezy_libvirt (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO machine: Initializing machine: buildbox2
INFO machine: - Provider: VagrantPlugins::ProviderLibvirt::Provider
INFO machine: - Box: #<Vagrant::Box:0x00000002134050>
INFO machine: - Data dir: /home/varac/vagrant/buildbox2/.vagrant/machines/buildbox2/libvirt
INFO command: With machine: buildbox2 (#<VagrantPlugins::ProviderLibvirt::Provider:0x0000000165eb80 @machine=#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>, @cap_logger=#<Log4r::Logger:0x0000000165d190 @fullname="vagrant::capability_host::vagrantplugins::providerlibvirt::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrantplugins::providerlibvirt", @parent=#<Log4r::Logger:0x0000000214d550 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001fd0dd0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001fd0d30>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001fb1868 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000214d3c0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @cap_host_chain=[[:libvirt, #<#<Class:0x0000000165e608>:0x0000000195ddb8>]], @cap_args=[#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>], @cap_caps={:virtualbox=>#<Vagrant::Registry:0x0000000165df00 @items={:forwarded_ports=>#<Proc:0x000000021f68f8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/virtualbox/plugin.rb:27>, :nic_mac_addresses=>#<Proc:0x000000021f6790@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/virtualbox/plugin.rb:32>, :public_address=>#<Proc:0x00000001ff4730@/opt/vagrant/embedded/gems/gems/vagrant-share-1.1.0/lib/vagrant-share.rb:39>}, @results_cache={}>, :hyperv=>#<Vagrant::Registry:0x0000000165dc80 @items={:public_address=>#<Proc:0x000000021cf050@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/hyperv/plugin.rb:25>}, @results_cache={}>, :docker=>#<Vagrant::Registry:0x0000000165d9b0 @items={:public_address=>#<Proc:0x000000021a92d8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/docker/plugin.rb:54>}, @results_cache={}>}>)
INFO interface: info: Bringing machine 'buildbox2' up with 'libvirt' provider...
Bringing machine 'buildbox2' up with 'libvirt' provider...
INFO batch_action: Enabling parallelization by default.
INFO batch_action: Disabling parallelization because only executing one action
INFO batch_action: Batch action will parallelize: false
INFO batch_action: Starting action: #<Vagrant::Machine:0x00000002e6dbe0> up {:destroy_on_error=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil}
INFO machine: Calling action: up on provider Libvirt (new)
DEBUG environment: Attempting to acquire process-lock: machine-action-f2fc5a49fc05e8b20de9a94244584cff
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-f2fc5a49fc05e8b20de9a94244584cff
INFO host: Autodetecting host type for [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>]
DEBUG host: Trying: opensuse
DEBUG host: Trying: gentoo
DEBUG host: Trying: freebsd
DEBUG host: Trying: slackware
DEBUG host: Trying: redhat
DEBUG host: Trying: darwin
DEBUG host: Trying: arch
DEBUG host: Trying: null
DEBUG host: Trying: bsd
DEBUG host: Trying: windows
DEBUG host: Trying: linux
INFO host: Detected: linux!
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x000000027860e8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f03ec6cf5d8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x007f03ec6cf5b0>
INFO connect_libvirt: Connecting to Libvirt (qemu:///system?no_verify=1&keyfile=/home/varac/.ssh/id_rsa) ...
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x007f03ec6e9460>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x007f03ec50c4d0>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x007f03ec523658>
INFO machine: Calling action: read_state on provider Libvirt (new)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x007f03ec5abc10>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f03ec5da038>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x007f03ec5da010>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadState:0x007f03ec62a178>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadState:0x007f03ec62a178>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x007f03ec5da010>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f03ec5da038>
INFO machine: New machine ID: nil
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x007f03ec523658>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x007f03ec756060>
INFO warden: Calling IN action: #<Proc:0x007f03ec7d9730@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::SetNameOfDomain:0x007f03ec755fe8>
INFO set_name_of_domain: Looking for domain buildbox2_1408708923_e155e742d480fa8a8669 through list [ <Fog::Compute::Libvirt::Server
id="708e6c91-c16a-4f08-a849-40fce0aa3652",
cpus=1,
cputime=0,
os_type="hvm",
memory_size=307200,
max_memory_size=307200,
name="buildbox_1407791641_7917001a13f66383e6f7",
arch="x86_64",
persistent=true,
domain_type="kvm",
uuid="708e6c91-c16a-4f08-a849-40fce0aa3652",
autostart=false,
nics=[ <Fog::Compute::Libvirt::Nic
mac="52:54:00:62:c0:d5",
id=nil,
type="network",
network="vagrant-libvirt",
bridge=nil,
model="virtio"
>, <Fog::Compute::Libvirt::Nic
mac="52:54:00:11:fe:db",
id=nil,
type="network",
network="test0",
bridge=nil,
model="virtio"
>],
volumes=[ <Fog::Compute::Libvirt::Volume
id="/home/varac/kvm/storagepool1/buildbox_1407791641_7917001a13f66383e6f7.img",
pool_name="default",
key="/home/varac/kvm/storagepool1/buildbox_1407791641_7917001a13f66383e6f7.img",
name="buildbox_1407791641_7917001a13f66383e6f7.img",
path="/home/varac/kvm/storagepool1/buildbox_1407791641_7917001a13f66383e6f7.img",
capacity=40,
allocation=3,
format_type="qcow2",
backing_volume=nil
>],
active=false,
boot_order=["hd"],
display={:type=>"vnc", :port=>"-1", :listen=>"127.0.0.1"},
state="shutoff"
>, <Fog::Compute::Libvirt::Server
id="f1bdb18b-1359-45af-895a-22fe43d0dc9c",
cpus=1,
cputime=32750000000,
os_type="hvm",
memory_size=307200,
max_memory_size=307200,
name="test_1408708638_74696916941fc81d39e0",
arch="x86_64",
persistent=true,
domain_type="kvm",
uuid="f1bdb18b-1359-45af-895a-22fe43d0dc9c",
autostart=false,
nics=[ <Fog::Compute::Libvirt::Nic
mac="52:54:00:38:c8:cc",
id=nil,
type="network",
network="vagrant-libvirt",
bridge=nil,
model="virtio"
>, <Fog::Compute::Libvirt::Nic
mac="52:54:00:6a:4c:6a",
id=nil,
type="network",
network="test0",
bridge=nil,
model="virtio"
>],
volumes=[ <Fog::Compute::Libvirt::Volume
id="/home/varac/kvm/storagepool1/test_1408708638_74696916941fc81d39e0.img",
pool_name="default",
key="/home/varac/kvm/storagepool1/test_1408708638_74696916941fc81d39e0.img",
name="test_1408708638_74696916941fc81d39e0.img",
path="/home/varac/kvm/storagepool1/test_1408708638_74696916941fc81d39e0.img",
capacity=40,
allocation=0,
format_type="qcow2",
backing_volume=nil
>],
active=true,
boot_order=["hd"],
display={:type=>"vnc", :port=>"5900", :listen=>"127.0.0.1"},
state="running"
>]
INFO set_name_of_domain: Looking for domain buildbox2_1408708923_e155e742d480fa8a8669
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::HandleStoragePool:0x007f03ec76d968>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::HandleBox:0x007f03ec787d90>
INFO handle_box: Machine already has box. HandleBox will not run.
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::HandleBoxImage:0x00000002659738>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateDomainVolume:0x0000000276b3b0>
INFO interface: info: Creating image (snapshot of base box volume).
INFO interface: info: ==> buildbox2: Creating image (snapshot of base box volume).
==> buildbox2: Creating image (snapshot of base box volume).
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateDomain:0x000000028b4320>
INFO interface: info: Creating domain with the following settings...
INFO interface: info: ==> buildbox2: Creating domain with the following settings...
==> buildbox2: Creating domain with the following settings...
INFO interface: info: -- Name: buildbox2_1408708923_e155e742d480fa8a8669
INFO interface: info: ==> buildbox2: -- Name: buildbox2_1408708923_e155e742d480fa8a8669
==> buildbox2: -- Name: buildbox2_1408708923_e155e742d480fa8a8669
INFO interface: info: -- Domain type: kvm
INFO interface: info: ==> buildbox2: -- Domain type: kvm
==> buildbox2: -- Domain type: kvm
INFO interface: info: -- Cpus: 1
INFO interface: info: ==> buildbox2: -- Cpus: 1
==> buildbox2: -- Cpus: 1
INFO interface: info: -- Memory: 300M
INFO interface: info: ==> buildbox2: -- Memory: 300M
==> buildbox2: -- Memory: 300M
INFO interface: info: -- Base box: leap-wheezy
INFO interface: info: ==> buildbox2: -- Base box: leap-wheezy
==> buildbox2: -- Base box: leap-wheezy
INFO interface: info: -- Storage pool: default
INFO interface: info: ==> buildbox2: -- Storage pool: default
==> buildbox2: -- Storage pool: default
INFO interface: info: -- Image: /home/varac/kvm/storagepool1/buildbox2_1408708923_e155e742d480fa8a8669.img
INFO interface: info: ==> buildbox2: -- Image: /home/varac/kvm/storagepool1/buildbox2_1408708923_e155e742d480fa8a8669.img
==> buildbox2: -- Image: /home/varac/kvm/storagepool1/buildbox2_1408708923_e155e742d480fa8a8669.img
INFO interface: info: -- Volume Cache: default
INFO interface: info: ==> buildbox2: -- Volume Cache: default
==> buildbox2: -- Volume Cache: default
INFO interface: info: -- Kernel:
INFO interface: info: ==> buildbox2: -- Kernel:
==> buildbox2: -- Kernel:
INFO interface: info: -- Initrd:
INFO interface: info: ==> buildbox2: -- Initrd:
==> buildbox2: -- Initrd:
INFO interface: info: -- Command line :
INFO interface: info: ==> buildbox2: -- Command line :
==> buildbox2: -- Command line :
INFO machine: New machine ID: "a72c37ab-5421-4ca2-ba5c-626bd7521c1b"
INFO warden: Calling IN action: #<VagrantPlugins::Cachier::Action::ConfigureBucketRoot:0x00000002e6c718>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Provision:0x007f03ec4761b0>
INFO provision: Checking provisioner sentinel if we should run...
INFO warden: Calling IN action: #<VagrantPlugins::Cachier::Action::InstallBuckets:0x007f03ec50c048>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworks:0x007f03ec598d18>
INFO create_networks: Using vagrant-libvirt at 192.168.121.0/24 as the management network
DEBUG create_networks: In config found network type forwarded_port options {:guest=>22, :host=>2222, :host_ip=>"127.0.0.1", :id=>"ssh", :auto_correct=>true, :protocol=>"tcp"}
DEBUG create_networks: Searching for network with options {:iface_type=>:private_network, :network_name=>"vagrant-libvirt", :ip=>"192.168.121.0", :netmask=>"255.255.255.0", :dhcp_enabled=>true, :forward_mode=>"nat"}
DEBUG create_networks: found existing network by ip, values are
DEBUG create_networks: Hash: {:name=>"vagrant-libvirt", :ip_address=>"192.168.121.1", :netmask=>"255.255.255.0", :network_address=>"192.168.121.0", :dhcp_enabled=>true, :bridge_name=>"virbr1", :created=>true, :active=>true, :autostart=>true, :libvirt_network=>#<Libvirt::Network:0x007f03ec4961e0 @connection=#<Libvirt::Connect:0x007f03ec48fb60>>}
DEBUG create_networks: Checking that network name does not clash with ip
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworkInterfaces:0x007f03ec616358>
INFO create_network_interfaces: Using vagrant-libvirt at 192.168.121.0/24 as the management network
DEBUG create_network_interfaces: In config found network type forwarded_port options {:guest=>22, :host=>2222, :host_ip=>"127.0.0.1", :id=>"ssh", :auto_correct=>true, :protocol=>"tcp"}
DEBUG create_network_interfaces: Adapter not specified so found slot 0
DEBUG create_network_interfaces: Found network by name
INFO create_network_interfaces: Creating network interface eth0 connected to network vagrant-libvirt.
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSValidIds:0x007f03ec65c920>
INFO warden: Calling IN action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x007f03ec692b88>
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO nfs: NFS pruning. Valid IDs: ["708e6c91-c16a-4f08-a849-40fce0aa3652", "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", "f1bdb18b-1359-45af-895a-22fe43d0dc9c"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>, #<Vagrant::UI::Prefixed:0x00000002d721a0 @logger=#<Log4r::Logger:0x00000002d72150 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000214d550 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001fd0dd0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001fd0d30>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001fb1868 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000214d3c0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @prefix=:buildbox2, @ui=#<Vagrant::UI::Colored:0x000000028ee048 @logger=#<Log4r::Logger:0x000000028edff8 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000214d550 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001fd0dd0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001fd0d30>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001fb1868 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000214d3c0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @lock=#<Mutex:0x0000000292f890>>>, ["708e6c91-c16a-4f08-a849-40fce0aa3652", "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", "f1bdb18b-1359-45af-895a-22fe43d0dc9c"]] (linux)
INFO linux: Pruning invalid NFS entries...
DEBUG linux: Valid ID: 708e6c91-c16a-4f08-a849-40fce0aa3652
INFO interface: info: Pruning invalid NFS exports. Administrator privileges will be required...
INFO interface: info: ==> buildbox2: Pruning invalid NFS exports. Administrator privileges will be required...
==> buildbox2: Pruning invalid NFS exports. Administrator privileges will be required...
INFO linux: Invalid ID, pruning: 168a99a6-2efa-4344-9b62-ab592806c2a2
[sudo] password for varac:
DEBUG linux: Valid ID: f1bdb18b-1359-45af-895a-22fe43d0dc9c
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x007f03ec6be3a0>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
INFO synced_folder_cleanup: Invoking synced folder cleanup for: nfs
INFO nfs: NFS pruning. Valid IDs: ["708e6c91-c16a-4f08-a849-40fce0aa3652", "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", "f1bdb18b-1359-45af-895a-22fe43d0dc9c"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>, #<Vagrant::UI::Prefixed:0x00000002d721a0 @logger=#<Log4r::Logger:0x00000002d72150 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000214d550 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001fd0dd0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001fd0d30>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001fb1868 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000214d3c0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @prefix=:buildbox2, @ui=#<Vagrant::UI::Colored:0x000000028ee048 @logger=#<Log4r::Logger:0x000000028edff8 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000214d550 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001fd0dd0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001fd0d30>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001fb1868 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000214d3c0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @lock=#<Mutex:0x0000000292f890>>>, ["708e6c91-c16a-4f08-a849-40fce0aa3652", "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", "f1bdb18b-1359-45af-895a-22fe43d0dc9c"]] (linux)
INFO linux: Pruning invalid NFS entries...
DEBUG linux: Valid ID: 708e6c91-c16a-4f08-a849-40fce0aa3652
DEBUG linux: Valid ID: f1bdb18b-1359-45af-895a-22fe43d0dc9c
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolders:0x007f03ec6e7778>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
INFO synced_folders: Synced Folder Implementation: nfs
INFO synced_folders: - vagrant-cache: /home/varac/.vagrant.d/cache/leap-wheezy => /tmp/vagrant-cache
INFO synced_folders: - /vagrant: . => /vagrant
INFO synced_folders: Invoking synced folder prepare for: nfs
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x007f03ec705228>
INFO interface: info: Starting domain.
INFO interface: info: ==> buildbox2: Starting domain.
==> buildbox2: Starting domain.
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::WaitTillUp:0x007f03ec72a000>
INFO interface: info: Waiting for domain to get an IP address...
INFO interface: info: ==> buildbox2: Waiting for domain to get an IP address...
==> buildbox2: Waiting for domain to get an IP address...
INFO retryable: Retryable exception raised: #<Fog::Errors::TimeoutError: The specified wait_for timeout (2 seconds) was exceeded>
INFO retryable: Retryable exception raised: #<Fog::Errors::TimeoutError: The specified wait_for timeout (2 seconds) was exceeded>
INFO retryable: Retryable exception raised: #<Fog::Errors::TimeoutError: The specified wait_for timeout (2 seconds) was exceeded>
INFO retryable: Retryable exception raised: #<Fog::Errors::TimeoutError: The specified wait_for timeout (2 seconds) was exceeded>
INFO wait_till_up: Got IP address 192.168.121.90
INFO wait_till_up: Time for getting IP: 13.470139265060425
INFO interface: info: Waiting for SSH to become available...
INFO interface: info: ==> buildbox2: Waiting for SSH to become available...
==> buildbox2: Waiting for SSH to become available...
DEBUG ssh: Checking whether SSH is ready...
INFO machine: Calling action: read_ssh_info on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x007f03ec6cc400>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f03ec6dc5f8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x007f03ec6dc5a8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x007f03ec706240>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x007f03ec706240>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x007f03ec6dc5a8>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f03ec6dc5f8>
DEBUG ssh: Checking key permissions: /home/varac/.vagrant.d/insecure_private_key
INFO ssh: Attempting SSH connnection...
INFO ssh: Attempting to connect to SSH...
INFO ssh: - Host: 192.168.121.90
INFO ssh: - Port: 22
INFO ssh: - Username: vagrant
INFO ssh: - Password? false
INFO ssh: - Key Path: ["/home/varac/.vagrant.d/insecure_private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2014-08-22T14:02:21.005734 #10432] DEBUG -- net.ssh.transport.session[10098ac]: establishing connection to 192.168.121.90:22
D, [2014-08-22T14:02:21.006978 #10432] DEBUG -- net.ssh.transport.session[10098ac]: connection established
I, [2014-08-22T14:02:21.007457 #10432] INFO -- net.ssh.transport.server_version[10be75c]: negotiating protocol version
D, [2014-08-22T14:02:21.017267 #10432] DEBUG -- net.ssh.transport.server_version[10be75c]: remote is `SSH-2.0-OpenSSH_6.0p1 Debian-4'
D, [2014-08-22T14:02:21.017520 #10432] DEBUG -- net.ssh.transport.server_version[10be75c]: local is `SSH-2.0-Ruby/Net::SSH_2.9.1 x86_64-linux'
D, [2014-08-22T14:02:21.019358 #10432] DEBUG -- tcpsocket[1019af4]: read 984 bytes
D, [2014-08-22T14:02:21.019726 #10432] DEBUG -- tcpsocket[1019af4]: received packet nr 0 type 20 len 980
I, [2014-08-22T14:02:21.020011 #10432] INFO -- net.ssh.transport.algorithms[10feec4]: got KEXINIT from server
I, [2014-08-22T14:02:21.020640 #10432] INFO -- net.ssh.transport.algorithms[10feec4]: sending KEXINIT
D, [2014-08-22T14:02:21.021184 #10432] DEBUG -- tcpsocket[1019af4]: queueing packet nr 0 type 20 len 2020
D, [2014-08-22T14:02:21.021405 #10432] DEBUG -- tcpsocket[1019af4]: sent 2024 bytes
I, [2014-08-22T14:02:21.021498 #10432] INFO -- net.ssh.transport.algorithms[10feec4]: negotiating algorithms
D, [2014-08-22T14:02:21.021845 #10432] DEBUG -- net.ssh.transport.algorithms[10feec4]: negotiated:
* kex: diffie-hellman-group-exchange-sha1
* host_key: ssh-rsa
* encryption_server: aes128-cbc
* encryption_client: aes128-cbc
* hmac_client: hmac-sha1
* hmac_server: hmac-sha1
* compression_client: none
* compression_server: none
* language_client:
* language_server:
D, [2014-08-22T14:02:21.021953 #10432] DEBUG -- net.ssh.transport.algorithms[10feec4]: exchanging keys
D, [2014-08-22T14:02:21.022503 #10432] DEBUG -- tcpsocket[1019af4]: queueing packet nr 1 type 34 len 20
D, [2014-08-22T14:02:21.022675 #10432] DEBUG -- tcpsocket[1019af4]: sent 24 bytes
D, [2014-08-22T14:02:21.024561 #10432] DEBUG -- tcpsocket[1019af4]: read 152 bytes
D, [2014-08-22T14:02:21.024681 #10432] DEBUG -- tcpsocket[1019af4]: received packet nr 1 type 31 len 148
D, [2014-08-22T14:02:21.028637 #10432] DEBUG -- tcpsocket[1019af4]: queueing packet nr 2 type 32 len 140
D, [2014-08-22T14:02:21.028747 #10432] DEBUG -- tcpsocket[1019af4]: sent 144 bytes
D, [2014-08-22T14:02:21.031154 #10432] DEBUG -- tcpsocket[1019af4]: read 720 bytes
D, [2014-08-22T14:02:21.031314 #10432] DEBUG -- tcpsocket[1019af4]: received packet nr 2 type 33 len 700
D, [2014-08-22T14:02:21.033097 #10432] DEBUG -- tcpsocket[1019af4]: queueing packet nr 3 type 21 len 20
D, [2014-08-22T14:02:21.033217 #10432] DEBUG -- tcpsocket[1019af4]: sent 24 bytes
D, [2014-08-22T14:02:21.033347 #10432] DEBUG -- tcpsocket[1019af4]: received packet nr 3 type 21 len 12
D, [2014-08-22T14:02:21.033858 #10432] DEBUG -- net.ssh.authentication.session[13c7c78]: beginning authentication of `vagrant'
D, [2014-08-22T14:02:21.034033 #10432] DEBUG -- tcpsocket[1019af4]: queueing packet nr 4 type 5 len 28
D, [2014-08-22T14:02:21.034102 #10432] DEBUG -- tcpsocket[1019af4]: sent 52 bytes
D, [2014-08-22T14:02:21.070582 #10432] DEBUG -- tcpsocket[1019af4]: read 52 bytes
D, [2014-08-22T14:02:21.070765 #10432] DEBUG -- tcpsocket[1019af4]: received packet nr 4 type 6 len 28
D, [2014-08-22T14:02:21.070950 #10432] DEBUG -- net.ssh.authentication.session[13c7c78]: trying none
D, [2014-08-22T14:02:21.071179 #10432] DEBUG -- tcpsocket[1019af4]: queueing packet nr 5 type 50 len 44
D, [2014-08-22T14:02:21.071280 #10432] DEBUG -- tcpsocket[1019af4]: sent 68 bytes
D, [2014-08-22T14:02:21.071976 #10432] DEBUG -- tcpsocket[1019af4]: read 68 bytes
D, [2014-08-22T14:02:21.072162 #10432] DEBUG -- tcpsocket[1019af4]: received packet nr 5 type 51 len 44
D, [2014-08-22T14:02:21.072426 #10432] DEBUG -- net.ssh.authentication.session[13c7c78]: allowed methods: publickey,password
D, [2014-08-22T14:02:21.072646 #10432] DEBUG -- net.ssh.authentication.methods.none[13cb404]: none failed
D, [2014-08-22T14:02:21.072871 #10432] DEBUG -- net.ssh.authentication.session[13c7c78]: trying publickey
D, [2014-08-22T14:02:21.073685 #10432] DEBUG -- net.ssh.authentication.agent[1403cb4]: connecting to ssh-agent
D, [2014-08-22T14:02:21.074031 #10432] DEBUG -- net.ssh.authentication.agent[1403cb4]: sending agent request 1 len 44
D, [2014-08-22T14:02:21.074277 #10432] DEBUG -- net.ssh.authentication.agent[1403cb4]: received agent packet 2 len 5
D, [2014-08-22T14:02:21.074429 #10432] DEBUG -- net.ssh.authentication.agent[1403cb4]: sending agent request 11 len 0
D, [2014-08-22T14:02:21.074662 #10432] DEBUG -- net.ssh.authentication.agent[1403cb4]: received agent packet 12 len 569
D, [2014-08-22T14:02:21.075481 #10432] DEBUG -- net.ssh.authentication.methods.publickey[13efb24]: trying publickey (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
D, [2014-08-22T14:02:21.076062 #10432] DEBUG -- tcpsocket[1019af4]: queueing packet nr 6 type 50 len 348
D, [2014-08-22T14:02:21.076293 #10432] DEBUG -- tcpsocket[1019af4]: sent 372 bytes
D, [2014-08-22T14:02:21.081106 #10432] DEBUG -- tcpsocket[1019af4]: read 324 bytes
D, [2014-08-22T14:02:21.081239 #10432] DEBUG -- tcpsocket[1019af4]: received packet nr 6 type 60 len 300
D, [2014-08-22T14:02:21.083292 #10432] DEBUG -- tcpsocket[1019af4]: queueing packet nr 7 type 50 len 620
D, [2014-08-22T14:02:21.083365 #10432] DEBUG -- tcpsocket[1019af4]: sent 644 bytes
D, [2014-08-22T14:02:21.084333 #10432] DEBUG -- tcpsocket[1019af4]: read 36 bytes
D, [2014-08-22T14:02:21.084449 #10432] DEBUG -- tcpsocket[1019af4]: received packet nr 7 type 52 len 12
D, [2014-08-22T14:02:21.084514 #10432] DEBUG -- net.ssh.authentication.methods.publickey[13efb24]: publickey succeeded (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
DEBUG ssh: == Net-SSH connection debug-level log END ==
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
INFO machine: Calling action: read_ssh_info on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x007f03ec43f4d0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f03ec458098>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x007f03ec45ff50>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x007f03ec552638>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x007f03ec552638>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x007f03ec45ff50>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f03ec458098>
INFO wait_till_up: Time for SSH ready: 0.26542186737060547
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x007f03ec7498d8>
INFO interface: info: Starting domain.
INFO interface: info: ==> buildbox2: Starting domain.
==> buildbox2: Starting domain.
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::WaitTillUp:0x007f03ec7668e8>
INFO interface: info: Waiting for domain to get an IP address...
INFO interface: info: ==> buildbox2: Waiting for domain to get an IP address...
==> buildbox2: Waiting for domain to get an IP address...
INFO wait_till_up: Got IP address 192.168.121.90
INFO wait_till_up: Time for getting IP: 0.026262998580932617
INFO interface: info: Waiting for SSH to become available...
INFO interface: info: ==> buildbox2: Waiting for SSH to become available...
==> buildbox2: Waiting for SSH to become available...
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
INFO wait_till_up: Time for SSH ready: 0.022470712661743164
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ForwardPorts:0x007f03ec77eba0>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSSettings:0x007f03ec797100>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ShareFolders:0x007f03ec7a8d10>
INFO interface: info: Creating shared folders metadata...
INFO interface: info: ==> buildbox2: Creating shared folders metadata...
==> buildbox2: Creating shared folders metadata...
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SetHostname:0x007f03ec7c28a0>
INFO warden: Calling IN action: #<Proc:0x007f03ec7c2850@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x007f03ec7c2850@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::SetHostname:0x007f03ec7c28a0>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ShareFolders:0x007f03ec7a8d10>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
INFO nfs: Using NFS, preparing NFS settings by reading host IP and machine IP
network name = vagrant-libvirt
INFO machine: Calling action: read_ssh_info on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000002691b10>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000276db88>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x0000000276db60>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x0000000297a278>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x0000000297a278>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x0000000276db60>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000276db88>
INFO nfs: host IP: 192.168.121.1 machine IP: 192.168.121.90
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSSettings:0x007f03ec797100>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ForwardPorts:0x007f03ec77eba0>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::WaitTillUp:0x007f03ec7668e8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x007f03ec7498d8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::WaitTillUp:0x007f03ec72a000>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x007f03ec705228>
INFO synced_folders: Invoking synced folder enable: nfs
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
INFO guest: Autodetecting host type for [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>]
DEBUG guest: Trying: mint
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/issue | grep 'Linux Mint' (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: pld
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/pld-release (sudo=false)
DEBUG ssh: stderr: cat:
DEBUG ssh: stderr: /etc/pld-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr:
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: suse
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/SuSE-release (sudo=false)
DEBUG ssh: stderr: cat:
DEBUG ssh: stderr: /etc/SuSE-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr:
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: ubuntu
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/issue | grep 'Ubuntu' (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: fedora
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: grep 'Fedora release [12][67890]' /etc/redhat-release (sudo=false)
DEBUG ssh: stderr: grep:
DEBUG ssh: stderr: /etc/redhat-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr:
DEBUG ssh: Exit status: 2
DEBUG guest: Trying: debian
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/issue | grep 'Debian' (sudo=false)
DEBUG ssh: stdout: Debian GNU/Linux 7.0 \n \l
DEBUG ssh: Exit status: 0
INFO guest: Detected: debian!
DEBUG guest: Searching for cap: nfs_client_installed
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: nfs_client_installed in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: nfs_client_installed
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: nfs_client_installed in linux
INFO guest: Execute capability: nfs_client_installed [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -x /sbin/mount.nfs (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG environment: Attempting to acquire process-lock: nfs-export
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: nfs-export
INFO interface: info: Exporting NFS shared folders...
INFO interface: info: ==> buildbox2: Exporting NFS shared folders...
==> buildbox2: Exporting NFS shared folders...
DEBUG host: Searching for cap: nfs_export
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_export in linux
INFO host: Execute capability: nfs_export [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>, #<Vagrant::UI::Prefixed:0x00000002d721a0 @logger=#<Log4r::Logger:0x00000002d72150 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000214d550 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001fd0dd0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001fd0d30>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001fb1868 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000214d3c0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @prefix=:buildbox2, @ui=#<Vagrant::UI::Colored:0x000000028ee048 @logger=#<Log4r::Logger:0x000000028edff8 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000214d550 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001fd0dd0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001fd0d30>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001fb1868 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000214d3c0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @lock=#<Mutex:0x0000000292f890>>>, "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", ["192.168.121.90"], {"vagrant-cache"=>{:id=>"vagrant-cache", :type=>:nfs, :mount_options=>["rw", "vers=3", "tcp", "nolock"], :guestpath=>"/tmp/vagrant-cache", :hostpath=>"/home/varac/.vagrant.d/cache/leap-wheezy", :disabled=>false, :map_uid=>1000, :map_gid=>1000, :nfs_udp=>true, :nfs_version=>3, :uuid=>"360431713"}, "/vagrant"=>{:guestpath=>"/vagrant", :hostpath=>"/home/varac/vagrant/buildbox2", :disabled=>false, :map_uid=>1000, :map_gid=>1000, :nfs_udp=>true, :nfs_version=>3, :uuid=>"467955075"}}] (linux)
DEBUG host: Searching for cap: nfs_apply_command
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_apply_command in linux
INFO host: Execute capability: nfs_apply_command [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
DEBUG host: Searching for cap: nfs_check_command
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_check_command in linux
INFO host: Execute capability: nfs_check_command [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
DEBUG host: Searching for cap: nfs_start_command
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_start_command in linux
INFO host: Execute capability: nfs_start_command [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
INFO interface: info: Preparing to edit /etc/exports. Administrator privileges will be required...
INFO interface: info: ==> buildbox2: Preparing to edit /etc/exports. Administrator privileges will be required...
==> buildbox2: Preparing to edit /etc/exports. Administrator privileges will be required...
nfsd running
INFO environment: Released process lock: nfs-export
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO interface: info: Mounting NFS shared folders...
INFO interface: info: ==> buildbox2: Mounting NFS shared folders...
==> buildbox2: Mounting NFS shared folders...
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: mount_nfs_folder
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: mount_nfs_folder in linux
INFO guest: Execute capability: mount_nfs_folder [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>, "192.168.121.1", {"vagrant-cache"=>{:id=>"vagrant-cache", :type=>:nfs, :mount_options=>["rw", "vers=3", "tcp", "nolock"], :guestpath=>"/tmp/vagrant-cache", :hostpath=>"/home/varac/.vagrant.d/cache/leap-wheezy", :disabled=>false, :map_uid=>1000, :map_gid=>1000, :nfs_udp=>true, :nfs_version=>3, :uuid=>"360431713", :linux__nfs_options=>["rw", "no_subtree_check", "all_squash", "anonuid=1000", "anongid=1000", "fsid=360431713"]}, "/vagrant"=>{:guestpath=>"/vagrant", :hostpath=>"/home/varac/vagrant/buildbox2", :disabled=>false, :map_uid=>1000, :map_gid=>1000, :nfs_udp=>true, :nfs_version=>3, :uuid=>"467955075", :linux__nfs_options=>["rw", "no_subtree_check", "all_squash", "anonuid=1000", "anongid=1000", "fsid=467955075"]}}] (debian)
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: shell_expand_guest_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: shell_expand_guest_path in linux
INFO guest: Execute capability: shell_expand_guest_path [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>, "/tmp/vagrant-cache"] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: echo; printf /tmp/vagrant-cache (sudo=false)
DEBUG ssh: stdout:
DEBUG ssh: Exit status: 0
DEBUG ssh: stdout: /tmp/vagrant-cache
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /tmp/vagrant-cache (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mount -o 'rw,vers=3,tcp,nolock' 192.168.121.1:'/home/varac/.vagrant.d/cache/leap-wheezy' /tmp/vagrant-cache (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -x /sbin/initctl (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: shell_expand_guest_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: shell_expand_guest_path in linux
INFO guest: Execute capability: shell_expand_guest_path [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>, "/vagrant"] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: echo; printf /vagrant (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: stdout:
/vagrant
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /vagrant (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mount -o 'vers=3,udp' 192.168.121.1:'/home/varac/vagrant/buildbox2' /vagrant (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -x /sbin/initctl (sudo=false)
DEBUG ssh: Exit status: 1
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::SyncedFolders:0x007f03ec6e7778>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x007f03ec6be3a0>
INFO warden: Calling OUT action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x007f03ec692b88>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSValidIds:0x007f03ec65c920>
INFO interface: info: Configuring and enabling network interfaces...
INFO interface: info: ==> buildbox2: Configuring and enabling network interfaces...
==> buildbox2: Configuring and enabling network interfaces...
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: configure_networks
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: configure_networks in debian
INFO guest: Execute capability: configure_networks [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>, []] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: sed -e '/^#VAGRANT-BEGIN/,/^#VAGRANT-END/ d' /etc/network/interfaces > /tmp/vagrant-network-interfaces (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: su -c 'cat /tmp/vagrant-network-interfaces > /etc/network/interfaces' (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: rm /tmp/vagrant-network-interfaces (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Uploading: /tmp/vagrant20140822-10432-umvlo5 to /tmp/vagrant-network-entry
DEBUG ssh: Re-using SSH connection.
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /tmp/vagrant-network-entry >> /etc/network/interfaces (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: rm /tmp/vagrant-network-entry (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworkInterfaces:0x007f03ec616358>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworks:0x007f03ec598d18>
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /tmp/vagrant-cache (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
INFO clean: 'chmod'ing bucket root dir to 777...
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: chmod 777 /tmp/vagrant-cache (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_cache_dir in debian
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: chef_file_cache_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: chef_file_cache_path in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: gemdir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: gemdir in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: pacman_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: yum_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: rvm_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: rvm_path in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_cacher_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_cacher_dir in debian
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_lists_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_lists_dir in debian
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: composer_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: composer_path in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: npm_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: npm_cache_dir in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: zypper_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
INFO interface: info: Configuring cache buckets...
INFO interface: info: ==> buildbox2: Configuring cache buckets...
==> buildbox2: Configuring cache buckets...
INFO clean: Installing apt with configs {}
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_cache_dir in debian
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_cache_dir in debian
INFO guest: Execute capability: apt_cache_dir [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /tmp/vagrant-cache/apt (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -L /var/cache/apt/archives (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p `dirname /var/cache/apt/archives` (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test "$(ls -A /tmp/vagrant-cache/apt 2>/dev/null)" (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: rm -rf /var/cache/apt/archives (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: ln -s /tmp/vagrant-cache/apt /var/cache/apt/archives (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /tmp/vagrant-cache/apt/partial (sudo=false)
DEBUG ssh: Exit status: 0
INFO clean: Installing chef with configs {}
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: chef_file_cache_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: chef_file_cache_path in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: chef_file_cache_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: chef_file_cache_path in linux
INFO guest: Execute capability: chef_file_cache_path [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
INFO clean: Installing gem with configs {}
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: gemdir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: gemdir in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: gemdir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: gemdir in linux
INFO guest: Execute capability: gemdir [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: which gem (sudo=false)
DEBUG ssh: stdout: /usr/bin/gem
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: gem env gemdir (sudo=false)
DEBUG ssh: stdout: /var/lib/gems/1.9.1
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /tmp/vagrant-cache/gem/1.9.1 (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -L /var/lib/gems/1.9.1/cache (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p `dirname /var/lib/gems/1.9.1/cache` (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test "$(ls -A /tmp/vagrant-cache/gem/1.9.1 2>/dev/null)" (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test "$(ls -A /var/lib/gems/1.9.1/cache 2>/dev/null)" (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: rm -rf /var/lib/gems/1.9.1/cache (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: ln -s /tmp/vagrant-cache/gem/1.9.1 /var/lib/gems/1.9.1/cache (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
INFO clean: Installing rvm with configs {}
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: rvm_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: rvm_path in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: rvm_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: rvm_path in linux
INFO guest: Execute capability: rvm_path [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: rvm info (sudo=false)
DEBUG ssh: stderr: bash: line 2: rvm: command not found
DEBUG ssh: Exit status: 127
INFO clean: Installing apt_cacher with configs {}
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_cacher_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_cacher_dir in debian
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_cacher_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_cacher_dir in debian
INFO guest: Execute capability: apt_cacher_dir [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -f /etc/apt-cacher-ng/acng.conf (sudo=false)
DEBUG ssh: Exit status: 1
INFO clean: Installing apt_lists with configs {}
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_lists_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_lists_dir in debian
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: apt_lists_dir
DEBUG guest: Checking in: debian
DEBUG guest: Found cap: apt_lists_dir in debian
INFO guest: Execute capability: apt_lists_dir [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /tmp/vagrant-cache/apt_lists (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -L /var/lib/apt/lists (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p `dirname /var/lib/apt/lists` (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test "$(ls -A /tmp/vagrant-cache/apt_lists 2>/dev/null)" (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: rm -rf /var/lib/apt/lists (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: ln -s /tmp/vagrant-cache/apt_lists /var/lib/apt/lists (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /tmp/vagrant-cache/apt_lists/partial (sudo=false)
DEBUG ssh: Exit status: 0
INFO clean: Installing composer with configs {}
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: composer_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: composer_path in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: composer_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: composer_path in linux
INFO guest: Execute capability: composer_path [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: which php (sudo=false)
DEBUG ssh: Exit status: 1
INFO clean: Installing npm with configs {}
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: npm_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: npm_cache_dir in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: npm_cache_dir
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: npm_cache_dir in linux
INFO guest: Execute capability: npm_cache_dir [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: which npm (sudo=false)
DEBUG ssh: Exit status: 1
INFO warden: Calling OUT action: #<VagrantPlugins::Cachier::Action::InstallBuckets:0x007f03ec50c048>
INFO provision: Writing provisioning sentinel so we don't provision again
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Provision:0x007f03ec4761b0>
INFO warden: Calling OUT action: #<VagrantPlugins::Cachier::Action::ConfigureBucketRoot:0x00000002e6c718>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateDomain:0x000000028b4320>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateDomainVolume:0x0000000276b3b0>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::HandleBoxImage:0x00000002659738>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::HandleBox:0x007f03ec787d90>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::HandleStoragePool:0x007f03ec76d968>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::SetNameOfDomain:0x007f03ec755fe8>
INFO warden: Calling OUT action: #<Proc:0x007f03ec7d9730@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x007f03ec6e9460>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x007f03ec6cf5b0>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f03ec6cf5d8>
INFO environment: Released process lock: machine-action-f2fc5a49fc05e8b20de9a94244584cff
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO environment: Running hook: environment_unload
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x007f03ec7c1e78>
INFO global: Vagrant version: 1.6.3
INFO global: Ruby version: 2.0.0
INFO global: RubyGems version: 2.0.14
INFO global: VAGRANT_DEFAULT_PROVIDER="libvirt"
INFO global: VAGRANT_EXECUTABLE="/opt/vagrant/bin/../embedded/gems/gems/vagrant-1.6.3/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: - Platform = 0.4.0
INFO global: - builder = 3.2.2
INFO global: - bundler = 1.6.2
INFO global: - excon = 0.39.4
INFO global: - formatador = 0.2.5
INFO global: - mime-types = 1.25.1
INFO global: - net-ssh = 2.9.1
INFO global: - net-scp = 1.1.2
INFO global: - fog-core = 1.23.0
INFO global: - multi_json = 1.10.1
INFO global: - fog-json = 1.0.0
INFO global: - inflecto = 0.0.2
INFO global: - fog-brightbox = 0.2.0
INFO global: - fog-softlayer = 0.3.13
INFO global: - ipaddress = 0.8.0
INFO global: - nokogiri = 1.5.11
INFO global: - fog = 1.23.0
INFO global: - json = 1.8.1
INFO global: - open4 = 1.3.4
INFO global: - popen4 = 0.1.2
INFO global: - rdoc = 4.1.1
INFO global: - rest-client = 1.6.8
INFO global: - ruby-libvirt = 0.4.0
INFO global: - sahara = 0.0.17
INFO global: - vagrant-cachier = 0.9.0
INFO global: - vagrant-libvirt = 0.0.19
INFO global: - vagrant-login = 1.0.1
INFO global: - vagrant-share = 1.1.0
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/kernel_v1/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/openbsd/plugin.rb
INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/solaris/plugin.rb
INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/debian/plugin.rb
INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/smartos/plugin.rb
INFO manager: Registered plugin: SmartOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/funtoo/plugin.rb
INFO manager: Registered plugin: Funtoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/netbsd/plugin.rb
INFO manager: Registered plugin: NetBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/coreos/plugin.rb
INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/plugins/guests/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/windows/plugin.rb
INFO manager: Registered plugin: Windows guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/mint/plugin.rb
INFO manager: Registered plugin: Mint guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/plugins/guests/esxi/plugin.rb
INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/nixos/plugin.rb
INFO manager: Registered plugin: NixOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/suse/plugin.rb
INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/omnios/plugin.rb
INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/redhat/plugin.rb
INFO manager: Registered plugin: RedHat guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/darwin/plugin.rb
INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/linux/plugin.rb
INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/arch/plugin.rb
INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/guests/ubuntu/plugin.rb
INFO manager: Registered plugin: Ubuntu guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/plugins/guests/fedora/plugin.rb
INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/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.3/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.3/plugins/communicators/winrm/plugin.rb
INFO manager: Registered plugin: winrm communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/communicators/ssh/plugin.rb
INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/null/plugin.rb
INFO manager: Registered plugin: null host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/opensuse/plugin.rb
INFO manager: Registered plugin: OpenSUSE host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/bsd/plugin.rb
INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/windows/plugin.rb
INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/slackware/plugin.rb
INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/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.3/plugins/hosts/linux/plugin.rb
INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/hosts/arch/plugin.rb
INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/help/plugin.rb
INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/halt/plugin.rb
INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/rdp/plugin.rb
INFO manager: Registered plugin: rdp command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/status/plugin.rb
INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/suspend/plugin.rb
INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/ssh/plugin.rb
INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/reload/plugin.rb
INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/destroy/plugin.rb
INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/box/plugin.rb
INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/plugin/plugin.rb
INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/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.3/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.3/plugins/commands/provision/plugin.rb
INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/package/plugin.rb
INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/init/plugin.rb
INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/version/plugin.rb
INFO manager: Registered plugin: version command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/up/plugin.rb
INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/commands/resume/plugin.rb
INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/cfengine/plugin.rb
INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/salt/plugin.rb
INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/chef/plugin.rb
INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/puppet/plugin.rb
INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/shell/plugin.rb
INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/ansible/plugin.rb
INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/file/plugin.rb
INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/provisioners/docker/plugin.rb
INFO manager: Registered plugin: docker
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/kernel_v2/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/virtualbox/plugin.rb
INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.6.3/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.3/plugins/providers/docker/plugin.rb
INFO manager: Registered plugin: docker-provider
INFO global: Loading plugins!
INFO manager: Registered plugin: vagrant-login
INFO manager: Registered plugin: vagrant-share
INFO manager: Registered plugin: libvirt
INFO manager: Registered plugin: sahara
INFO manager: Registered plugin: vagrant-cachier
INFO vagrant: `vagrant` invoked: ["reload"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#<Vagrant::Environment:0x000000036d63e0>)
INFO environment: - cwd: /home/varac/vagrant/buildbox2
INFO environment: Home path: /home/varac/.vagrant.d
INFO environment: Local data path: /home/varac/vagrant/buildbox2/.vagrant
DEBUG environment: Creating: /home/varac/vagrant/buildbox2/.vagrant
INFO environment: Running hook: environment_plugins_loaded
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x000000037f30c0>
INFO environment: Running hook: environment_load
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000002dfc850>
INFO cli: CLI: [] "reload" []
DEBUG cli: Invoking command class: VagrantPlugins::CommandReload::Command []
DEBUG command: 'reload' each target VM...
DEBUG command: Getting target VMs for command. Arguments:
DEBUG command: -- names: []
DEBUG command: -- options: nil
DEBUG command: Loading all machines...
INFO loader: Set :home = #<Pathname:/home/varac/.vagrant.d/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:/home/varac/.vagrant.d/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/varac/.vagrant.d/Vagrantfile
INFO loader: Set :root = #<Pathname:/home/varac/vagrant/buildbox2/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:/home/varac/vagrant/buildbox2/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/varac/vagrant/buildbox2/Vagrantfile
INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: home (evaluating)
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO command: Active machine found with name buildbox2. Using provider: libvirt
INFO environment: Getting machine: buildbox2 (libvirt)
INFO environment: Uncached load of machine.
INFO loader: Set "27978400_machine_buildbox2" = [["2", #<Proc:0x000000037c1188@/home/varac/vagrant/buildbox2/Vagrantfile:2>]]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x000000037c1188@/home/varac/vagrant/buildbox2/Vagrantfile:2>]
INFO loader: Loading configuration in order: [:home, :root, "27978400_machine_buildbox2"]
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 27978400_machine_buildbox2 (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO box_collection: Box found: leap-wheezy (libvirt)
INFO loader: Set :"23079680_leap-wheezy_libvirt" = #<Pathname:/home/varac/.vagrant.d/boxes/leap-wheezy/0/libvirt/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:/home/varac/.vagrant.d/boxes/leap-wheezy/0/libvirt/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/varac/.vagrant.d/boxes/leap-wheezy/0/libvirt/Vagrantfile
Vagrant.require_plugin is deprecated and has no effect any longer.
Use `vagrant plugin` commands to manage plugins. This warning will
be removed in the next version of Vagrant.
INFO loader: Loading configuration in order: [:"23079680_leap-wheezy_libvirt", :home, :root, "27978400_machine_buildbox2"]
DEBUG loader: Loading from: 23079680_leap-wheezy_libvirt (evaluating)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 27978400_machine_buildbox2 (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO loader: Set :"27978400_vm_buildbox2_leap-wheezy_libvirt" = [["2", #<Proc:0x00000003678f88>]]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00000003678f88>]
INFO loader: Loading configuration in order: [:"23079680_leap-wheezy_libvirt", :home, :root, "27978400_machine_buildbox2", :"27978400_vm_buildbox2_leap-wheezy_libvirt"]
DEBUG loader: Loading from: 23079680_leap-wheezy_libvirt (cache)
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 27978400_machine_buildbox2 (cache)
DEBUG loader: Loading from: 27978400_vm_buildbox2_leap-wheezy_libvirt (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO machine: Initializing machine: buildbox2
INFO machine: - Provider: VagrantPlugins::ProviderLibvirt::Provider
INFO machine: - Box: #<Vagrant::Box:0x00000002b799c0>
INFO machine: - Data dir: /home/varac/vagrant/buildbox2/.vagrant/machines/buildbox2/libvirt
INFO command: With machine: buildbox2 (#<VagrantPlugins::ProviderLibvirt::Provider:0x00000003c1cf20 @machine=#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>, @cap_logger=#<Log4r::Logger:0x00000003c1ca20 @fullname="vagrant::capability_host::vagrantplugins::providerlibvirt::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrantplugins::providerlibvirt", @parent=#<Log4r::Logger:0x00000002f31a18 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000002db5310 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000002db5270>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000002d99e30 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000002f318b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @cap_host_chain=[[:libvirt, #<#<Class:0x00000003c1ced0>:0x00000003a71f68>]], @cap_args=[#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>], @cap_caps={:virtualbox=>#<Vagrant::Registry:0x00000003c1cd68 @items={:forwarded_ports=>#<Proc:0x00000002fdee20@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/virtualbox/plugin.rb:27>, :nic_mac_addresses=>#<Proc:0x00000002fded80@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/virtualbox/plugin.rb:32>, :public_address=>#<Proc:0x00000002ddce10@/opt/vagrant/embedded/gems/gems/vagrant-share-1.1.0/lib/vagrant-share.rb:39>}, @results_cache={}>, :hyperv=>#<Vagrant::Registry:0x00000003c1cca0 @items={:public_address=>#<Proc:0x00000002fb76b8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/hyperv/plugin.rb:25>}, @results_cache={}>, :docker=>#<Vagrant::Registry:0x00000003c1cbd8 @items={:public_address=>#<Proc:0x00000002f919b8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/plugins/providers/docker/plugin.rb:54>}, @results_cache={}>}>)
INFO machine: Calling action: reload on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
DEBUG environment: Attempting to acquire process-lock: machine-action-f2fc5a49fc05e8b20de9a94244584cff
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-f2fc5a49fc05e8b20de9a94244584cff
INFO host: Autodetecting host type for [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>]
DEBUG host: Trying: opensuse
DEBUG host: Trying: gentoo
DEBUG host: Trying: freebsd
DEBUG host: Trying: slackware
DEBUG host: Trying: redhat
DEBUG host: Trying: darwin
DEBUG host: Trying: arch
DEBUG host: Trying: null
DEBUG host: Trying: bsd
DEBUG host: Trying: windows
DEBUG host: Trying: linux
INFO host: Detected: linux!
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000003c79860>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x000000034a6c28>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000354ad28>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x0000000354fd28>
INFO machine: Calling action: read_state on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000356eac0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x000000040093b8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x00000004009390>
INFO connect_libvirt: Connecting to Libvirt (qemu:///system?no_verify=1&keyfile=/home/varac/.ssh/id_rsa) ...
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadState:0x00000004034ec8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadState:0x00000004034ec8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x00000004009390>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x000000040093b8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x0000000354fd28>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x0000000401a4b0>
INFO warden: Calling IN action: #<Proc:0x000000040b3458@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000401a370>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000401a320>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x0000000401a280>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ClearForwardedPorts:0x00000004044030>
INFO clear_forward_ports: No ssh pids found
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x0000000406b130>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x000000040ee5f8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x000000040f3738>
INFO machine: Calling action: read_state on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000410aa50>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000410f5f0>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x0000000410f5c8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadState:0x000000041208f0>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadState:0x000000041208f0>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x0000000410f5c8>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000410f5f0>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x000000040f3738>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x000000034bcfc8>
INFO warden: Calling IN action: #<Proc:0x0000000360aa10@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x000000034bcd98>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000003e4bbe8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00000003e5ada0>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00000003e5ada0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x00000003f11708>
INFO warden: Calling IN action: #<Proc:0x00000003fa06d8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Proc:0x00000003f10fd8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x000000034bca00>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000402a068>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00000004033fa0>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00000004033fa0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x0000000406be28>
INFO warden: Calling IN action: #<Proc:0x000000040b2620@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::HaltDomain:0x0000000406bdb0>
INFO interface: info: Halting domain...
INFO interface: info: ==> buildbox2: Halting domain...
==> buildbox2: Halting domain...
INFO halt_domain: Trying gracefull shutdown.
INFO warden: Calling IN action: #<Proc:0x0000000408a800@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Proc:0x000000034cbe88@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000406b090>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x0000000406b068>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00000004088500>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000003e1b830>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00000003e20498>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00000003e20498>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x00000003eceed0>
INFO warden: Calling IN action: #<Proc:0x00000003f63440@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00000003ecee30>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000004016b80>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x0000000401fca8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x0000000401fca8>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x0000000267cf80>
INFO warden: Calling IN action: #<Proc:0x000000040ae138@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworks:0x0000000267ff78>
INFO create_networks: Using vagrant-libvirt at 192.168.121.0/24 as the management network
DEBUG create_networks: In config found network type forwarded_port options {:guest=>22, :host=>2222, :host_ip=>"127.0.0.1", :id=>"ssh", :auto_correct=>true, :protocol=>"tcp"}
DEBUG create_networks: Searching for network with options {:iface_type=>:private_network, :network_name=>"vagrant-libvirt", :ip=>"192.168.121.0", :netmask=>"255.255.255.0", :dhcp_enabled=>true, :forward_mode=>"nat"}
DEBUG create_networks: found existing network by ip, values are
DEBUG create_networks: Hash: {:name=>"vagrant-libvirt", :ip_address=>"192.168.121.1", :netmask=>"255.255.255.0", :network_address=>"192.168.121.0", :dhcp_enabled=>true, :bridge_name=>"virbr1", :created=>true, :active=>true, :autostart=>true, :libvirt_network=>#<Libvirt::Network:0x000000040d7f60 @connection=#<Libvirt::Connect:0x00000003a969a8>>}
DEBUG create_networks: Checking that network name does not clash with ip
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSValidIds:0x0000000350ae08>
INFO warden: Calling IN action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x0000000360ae48>
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO nfs: NFS pruning. Valid IDs: ["708e6c91-c16a-4f08-a849-40fce0aa3652", "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", "f1bdb18b-1359-45af-895a-22fe43d0dc9c"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>, #<Vagrant::UI::Prefixed:0x00000003c7a170 @logger=#<Log4r::Logger:0x00000003c7a0f8 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000002f31a18 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000002db5310 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000002db5270>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000002d99e30 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000002f318b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @prefix=:buildbox2, @ui=#<Vagrant::UI::Colored:0x000000036d61d8 @logger=#<Log4r::Logger:0x000000036d6188 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000002f31a18 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000002db5310 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000002db5270>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000002d99e30 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000002f318b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @lock=#<Mutex:0x00000003713a60>>>, ["708e6c91-c16a-4f08-a849-40fce0aa3652", "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", "f1bdb18b-1359-45af-895a-22fe43d0dc9c"]] (linux)
INFO linux: Pruning invalid NFS entries...
DEBUG linux: Valid ID: 708e6c91-c16a-4f08-a849-40fce0aa3652
DEBUG linux: Valid ID: f1bdb18b-1359-45af-895a-22fe43d0dc9c
DEBUG linux: Valid ID: a72c37ab-5421-4ca2-ba5c-626bd7521c1b
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x00000003c59e98>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
INFO synced_folder_cleanup: Invoking synced folder cleanup for: nfs
INFO nfs: NFS pruning. Valid IDs: ["708e6c91-c16a-4f08-a849-40fce0aa3652", "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", "f1bdb18b-1359-45af-895a-22fe43d0dc9c"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>, #<Vagrant::UI::Prefixed:0x00000003c7a170 @logger=#<Log4r::Logger:0x00000003c7a0f8 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000002f31a18 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000002db5310 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000002db5270>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000002d99e30 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000002f318b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @prefix=:buildbox2, @ui=#<Vagrant::UI::Colored:0x000000036d61d8 @logger=#<Log4r::Logger:0x000000036d6188 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000002f31a18 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000002db5310 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000002db5270>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000002d99e30 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000002f318b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @lock=#<Mutex:0x00000003713a60>>>, ["708e6c91-c16a-4f08-a849-40fce0aa3652", "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", "f1bdb18b-1359-45af-895a-22fe43d0dc9c"]] (linux)
INFO linux: Pruning invalid NFS entries...
DEBUG linux: Valid ID: 708e6c91-c16a-4f08-a849-40fce0aa3652
DEBUG linux: Valid ID: f1bdb18b-1359-45af-895a-22fe43d0dc9c
DEBUG linux: Valid ID: a72c37ab-5421-4ca2-ba5c-626bd7521c1b
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolders:0x00000003e5f5d0>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
INFO synced_folders: Synced Folder Implementation: nfs
INFO synced_folders: - /vagrant: . => /vagrant
INFO synced_folders: Invoking synced folder prepare for: nfs
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x00000003eeb878>
INFO interface: info: Starting domain.
INFO interface: info: ==> buildbox2: Starting domain.
==> buildbox2: Starting domain.
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::WaitTillUp:0x00000003f7ee20>
INFO interface: info: Waiting for domain to get an IP address...
INFO interface: info: ==> buildbox2: Waiting for domain to get an IP address...
==> buildbox2: Waiting for domain to get an IP address...
INFO retryable: Retryable exception raised: #<Fog::Errors::TimeoutError: The specified wait_for timeout (2 seconds) was exceeded>
INFO retryable: Retryable exception raised: #<Fog::Errors::TimeoutError: The specified wait_for timeout (2 seconds) was exceeded>
INFO retryable: Retryable exception raised: #<Fog::Errors::TimeoutError: The specified wait_for timeout (2 seconds) was exceeded>
INFO wait_till_up: Got IP address 192.168.121.90
INFO wait_till_up: Time for getting IP: 10.315366268157959
INFO interface: info: Waiting for SSH to become available...
INFO interface: info: ==> buildbox2: Waiting for SSH to become available...
==> buildbox2: Waiting for SSH to become available...
DEBUG ssh: Checking whether SSH is ready...
INFO machine: Calling action: read_ssh_info on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000003f2c878>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000003f600b0>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x00000003f61be0>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x00000003fd1b98>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x00000003fd1b98>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x00000003f61be0>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000003f600b0>
DEBUG ssh: Checking key permissions: /home/varac/.vagrant.d/insecure_private_key
INFO ssh: Attempting SSH connnection...
INFO ssh: Attempting to connect to SSH...
INFO ssh: - Host: 192.168.121.90
INFO ssh: - Port: 22
INFO ssh: - Username: vagrant
INFO ssh: - Password? false
INFO ssh: - Key Path: ["/home/varac/.vagrant.d/insecure_private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2014-08-22T14:02:42.537496 #10803] DEBUG -- net.ssh.transport.session[20586e0]: establishing connection to 192.168.121.90:22
D, [2014-08-22T14:02:42.538502 #10803] DEBUG -- net.ssh.transport.session[20586e0]: connection established
I, [2014-08-22T14:02:42.538724 #10803] INFO -- net.ssh.transport.server_version[205b6ec]: negotiating protocol version
D, [2014-08-22T14:02:42.546503 #10803] DEBUG -- net.ssh.transport.server_version[205b6ec]: remote is `SSH-2.0-OpenSSH_6.0p1 Debian-4'
D, [2014-08-22T14:02:42.546727 #10803] DEBUG -- net.ssh.transport.server_version[205b6ec]: local is `SSH-2.0-Ruby/Net::SSH_2.9.1 x86_64-linux'
D, [2014-08-22T14:02:42.548698 #10803] DEBUG -- tcpsocket[205bf84]: read 984 bytes
D, [2014-08-22T14:02:42.549149 #10803] DEBUG -- tcpsocket[205bf84]: received packet nr 0 type 20 len 980
I, [2014-08-22T14:02:42.549454 #10803] INFO -- net.ssh.transport.algorithms[205a1d4]: got KEXINIT from server
I, [2014-08-22T14:02:42.550226 #10803] INFO -- net.ssh.transport.algorithms[205a1d4]: sending KEXINIT
D, [2014-08-22T14:02:42.551020 #10803] DEBUG -- tcpsocket[205bf84]: queueing packet nr 0 type 20 len 2020
D, [2014-08-22T14:02:42.551302 #10803] DEBUG -- tcpsocket[205bf84]: sent 2024 bytes
I, [2014-08-22T14:02:42.551448 #10803] INFO -- net.ssh.transport.algorithms[205a1d4]: negotiating algorithms
D, [2014-08-22T14:02:42.551997 #10803] DEBUG -- net.ssh.transport.algorithms[205a1d4]: negotiated:
* kex: diffie-hellman-group-exchange-sha1
* host_key: ssh-rsa
* encryption_server: aes128-cbc
* encryption_client: aes128-cbc
* hmac_client: hmac-sha1
* hmac_server: hmac-sha1
* compression_client: none
* compression_server: none
* language_client:
* language_server:
D, [2014-08-22T14:02:42.552222 #10803] DEBUG -- net.ssh.transport.algorithms[205a1d4]: exchanging keys
D, [2014-08-22T14:02:42.552976 #10803] DEBUG -- tcpsocket[205bf84]: queueing packet nr 1 type 34 len 20
D, [2014-08-22T14:02:42.553062 #10803] DEBUG -- tcpsocket[205bf84]: sent 24 bytes
D, [2014-08-22T14:02:42.555969 #10803] DEBUG -- tcpsocket[205bf84]: read 152 bytes
D, [2014-08-22T14:02:42.556290 #10803] DEBUG -- tcpsocket[205bf84]: received packet nr 1 type 31 len 148
D, [2014-08-22T14:02:42.558444 #10803] DEBUG -- tcpsocket[205bf84]: queueing packet nr 2 type 32 len 140
D, [2014-08-22T14:02:42.558549 #10803] DEBUG -- tcpsocket[205bf84]: sent 144 bytes
D, [2014-08-22T14:02:42.563338 #10803] DEBUG -- tcpsocket[205bf84]: read 720 bytes
D, [2014-08-22T14:02:42.563541 #10803] DEBUG -- tcpsocket[205bf84]: received packet nr 2 type 33 len 700
D, [2014-08-22T14:02:42.565320 #10803] DEBUG -- tcpsocket[205bf84]: queueing packet nr 3 type 21 len 20
D, [2014-08-22T14:02:42.565422 #10803] DEBUG -- tcpsocket[205bf84]: sent 24 bytes
D, [2014-08-22T14:02:42.565533 #10803] DEBUG -- tcpsocket[205bf84]: received packet nr 3 type 21 len 12
D, [2014-08-22T14:02:42.566010 #10803] DEBUG -- net.ssh.authentication.session[20719d8]: beginning authentication of `vagrant'
D, [2014-08-22T14:02:42.566181 #10803] DEBUG -- tcpsocket[205bf84]: queueing packet nr 4 type 5 len 28
D, [2014-08-22T14:02:42.566247 #10803] DEBUG -- tcpsocket[205bf84]: sent 52 bytes
D, [2014-08-22T14:02:42.605690 #10803] DEBUG -- tcpsocket[205bf84]: read 52 bytes
D, [2014-08-22T14:02:42.605915 #10803] DEBUG -- tcpsocket[205bf84]: received packet nr 4 type 6 len 28
D, [2014-08-22T14:02:42.606097 #10803] DEBUG -- net.ssh.authentication.session[20719d8]: trying none
D, [2014-08-22T14:02:42.606332 #10803] DEBUG -- tcpsocket[205bf84]: queueing packet nr 5 type 50 len 44
D, [2014-08-22T14:02:42.606422 #10803] DEBUG -- tcpsocket[205bf84]: sent 68 bytes
D, [2014-08-22T14:02:42.607145 #10803] DEBUG -- tcpsocket[205bf84]: read 68 bytes
D, [2014-08-22T14:02:42.607298 #10803] DEBUG -- tcpsocket[205bf84]: received packet nr 5 type 51 len 44
D, [2014-08-22T14:02:42.607426 #10803] DEBUG -- net.ssh.authentication.session[20719d8]: allowed methods: publickey,password
D, [2014-08-22T14:02:42.607522 #10803] DEBUG -- net.ssh.authentication.methods.none[2070358]: none failed
D, [2014-08-22T14:02:42.607631 #10803] DEBUG -- net.ssh.authentication.session[20719d8]: trying publickey
D, [2014-08-22T14:02:42.608038 #10803] DEBUG -- net.ssh.authentication.agent[2075b28]: connecting to ssh-agent
D, [2014-08-22T14:02:42.608296 #10803] DEBUG -- net.ssh.authentication.agent[2075b28]: sending agent request 1 len 44
D, [2014-08-22T14:02:42.608577 #10803] DEBUG -- net.ssh.authentication.agent[2075b28]: received agent packet 2 len 5
D, [2014-08-22T14:02:42.608758 #10803] DEBUG -- net.ssh.authentication.agent[2075b28]: sending agent request 11 len 0
D, [2014-08-22T14:02:42.609053 #10803] DEBUG -- net.ssh.authentication.agent[2075b28]: received agent packet 12 len 569
D, [2014-08-22T14:02:42.610098 #10803] DEBUG -- net.ssh.authentication.methods.publickey[2075dd0]: trying publickey (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
D, [2014-08-22T14:02:42.610839 #10803] DEBUG -- tcpsocket[205bf84]: queueing packet nr 6 type 50 len 348
D, [2014-08-22T14:02:42.611090 #10803] DEBUG -- tcpsocket[205bf84]: sent 372 bytes
D, [2014-08-22T14:02:42.617065 #10803] DEBUG -- tcpsocket[205bf84]: read 324 bytes
D, [2014-08-22T14:02:42.617344 #10803] DEBUG -- tcpsocket[205bf84]: received packet nr 6 type 60 len 300
D, [2014-08-22T14:02:42.621158 #10803] DEBUG -- tcpsocket[205bf84]: queueing packet nr 7 type 50 len 620
D, [2014-08-22T14:02:42.621310 #10803] DEBUG -- tcpsocket[205bf84]: sent 644 bytes
D, [2014-08-22T14:02:42.622829 #10803] DEBUG -- tcpsocket[205bf84]: read 36 bytes
D, [2014-08-22T14:02:42.622999 #10803] DEBUG -- tcpsocket[205bf84]: received packet nr 7 type 52 len 12
D, [2014-08-22T14:02:42.623115 #10803] DEBUG -- net.ssh.authentication.methods.publickey[2075dd0]: publickey succeeded (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
DEBUG ssh: == Net-SSH connection debug-level log END ==
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
INFO machine: Calling action: read_ssh_info on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000004111008>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000004115ba8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x00000004115b80>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x00000004133d38>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x00000004133d38>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x00000004115b80>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000004115ba8>
INFO wait_till_up: Time for SSH ready: 0.31157875061035156
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ForwardPorts:0x00000003fea288>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSSettings:0x000000040260f8>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ShareFolders:0x000000040557b8>
INFO interface: info: Creating shared folders metadata...
INFO interface: info: ==> buildbox2: Creating shared folders metadata...
==> buildbox2: Creating shared folders metadata...
INFO warden: Calling IN action: #<Proc:0x0000000407fba8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Proc:0x00000003ecedb8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Proc:0x00000004088460@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x00000004088460@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x00000003ecedb8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x0000000407fba8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ShareFolders:0x000000040557b8>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
INFO nfs: Using NFS, preparing NFS settings by reading host IP and machine IP
network name = vagrant-libvirt
INFO machine: Calling action: read_ssh_info on provider Libvirt (a72c37ab-5421-4ca2-ba5c-626bd7521c1b)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000003eb9eb8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000003ece480>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x00000003ece458>
INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x00000003f76bf8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ReadSSHInfo:0x00000003f76bf8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x00000003ece458>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000003ece480>
INFO nfs: host IP: 192.168.121.1 machine IP: 192.168.121.90
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSSettings:0x000000040260f8>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ForwardPorts:0x00000003fea288>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::WaitTillUp:0x00000003f7ee20>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x00000003eeb878>
INFO synced_folders: Invoking synced folder enable: nfs
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
INFO guest: Autodetecting host type for [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>]
DEBUG guest: Trying: mint
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/issue | grep 'Linux Mint' (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: pld
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/pld-release (sudo=false)
DEBUG ssh: stderr: cat:
DEBUG ssh: stderr: /etc/pld-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr:
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: suse
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/SuSE-release (sudo=false)
DEBUG ssh: stderr: cat:
DEBUG ssh: stderr: /etc/SuSE-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr:
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: ubuntu
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/issue | grep 'Ubuntu' (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: fedora
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: grep 'Fedora release [12][67890]' /etc/redhat-release (sudo=false)
DEBUG ssh: stderr: grep:
DEBUG ssh: stderr: /etc/redhat-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr:
DEBUG ssh: Exit status: 2
DEBUG guest: Trying: debian
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: cat /etc/issue | grep 'Debian' (sudo=false)
DEBUG ssh: stdout: Debian GNU/Linux 7.0 \n \l
DEBUG ssh: Exit status: 0
INFO guest: Detected: debian!
DEBUG guest: Searching for cap: nfs_client_installed
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: nfs_client_installed in linux
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: nfs_client_installed
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: nfs_client_installed in linux
INFO guest: Execute capability: nfs_client_installed [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -x /sbin/mount.nfs (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG environment: Attempting to acquire process-lock: nfs-export
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: nfs-export
INFO interface: info: Exporting NFS shared folders...
INFO interface: info: ==> buildbox2: Exporting NFS shared folders...
==> buildbox2: Exporting NFS shared folders...
DEBUG host: Searching for cap: nfs_export
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_export in linux
INFO host: Execute capability: nfs_export [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>, #<Vagrant::UI::Prefixed:0x00000003c7a170 @logger=#<Log4r::Logger:0x00000003c7a0f8 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000002f31a18 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000002db5310 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000002db5270>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000002d99e30 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000002f318b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @prefix=:buildbox2, @ui=#<Vagrant::UI::Colored:0x000000036d61d8 @logger=#<Log4r::Logger:0x000000036d6188 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000002f31a18 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000002db5310 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000002db5270>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000002d99e30 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000002f318b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @lock=#<Mutex:0x00000003713a60>>>, "a72c37ab-5421-4ca2-ba5c-626bd7521c1b", ["192.168.121.90"], {"/vagrant"=>{:guestpath=>"/vagrant", :hostpath=>"/home/varac/vagrant/buildbox2", :disabled=>false, :map_uid=>1000, :map_gid=>1000, :nfs_udp=>true, :nfs_version=>3, :uuid=>"467955075"}}] (linux)
DEBUG host: Searching for cap: nfs_apply_command
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_apply_command in linux
INFO host: Execute capability: nfs_apply_command [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
DEBUG host: Searching for cap: nfs_check_command
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_check_command in linux
INFO host: Execute capability: nfs_check_command [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
DEBUG host: Searching for cap: nfs_start_command
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_start_command in linux
INFO host: Execute capability: nfs_start_command [#<Vagrant::Environment: /home/varac/vagrant/buildbox2>] (linux)
INFO interface: info: Preparing to edit /etc/exports. Administrator privileges will be required...
INFO interface: info: ==> buildbox2: Preparing to edit /etc/exports. Administrator privileges will be required...
==> buildbox2: Preparing to edit /etc/exports. Administrator privileges will be required...
nfsd running
INFO environment: Released process lock: nfs-export
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO interface: info: Mounting NFS shared folders...
INFO interface: info: ==> buildbox2: Mounting NFS shared folders...
==> buildbox2: Mounting NFS shared folders...
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: mount_nfs_folder
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: mount_nfs_folder in linux
INFO guest: Execute capability: mount_nfs_folder [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>, "192.168.121.1", {"/vagrant"=>{:guestpath=>"/vagrant", :hostpath=>"/home/varac/vagrant/buildbox2", :disabled=>false, :map_uid=>1000, :map_gid=>1000, :nfs_udp=>true, :nfs_version=>3, :uuid=>"467955075", :linux__nfs_options=>["rw", "no_subtree_check", "all_squash", "anonuid=1000", "anongid=1000", "fsid=467955075"]}}] (debian)
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG guest: Searching for cap: shell_expand_guest_path
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: shell_expand_guest_path in linux
INFO guest: Execute capability: shell_expand_guest_path [#<Vagrant::Machine: buildbox2 (VagrantPlugins::ProviderLibvirt::Provider)>, "/vagrant"] (debian)
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: echo; printf /vagrant (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: stdout:
/vagrant
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mkdir -p /vagrant (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: mount -o 'vers=3,udp' 192.168.121.1:'/home/varac/vagrant/buildbox2' /vagrant (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: test -x /sbin/initctl (sudo=false)
DEBUG ssh: Exit status: 1
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::SyncedFolders:0x00000003e5f5d0>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x00000003c59e98>
INFO warden: Calling OUT action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x0000000360ae48>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSValidIds:0x0000000350ae08>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworks:0x0000000267ff78>
INFO warden: Calling OUT action: #<Proc:0x000000040ae138@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x00000003ecee30>
INFO warden: Calling OUT action: #<Proc:0x00000003f63440@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x00000004088500>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x0000000406b068>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000406b090>
INFO warden: Calling OUT action: #<Proc:0x000000034cbe88@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x0000000408a800@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::HaltDomain:0x0000000406bdb0>
INFO warden: Calling OUT action: #<Proc:0x000000040b2620@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x000000034bca00>
INFO warden: Calling OUT action: #<Proc:0x00000003f10fd8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x00000003fa06d8@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x000000034bcd98>
INFO warden: Calling OUT action: #<Proc:0x0000000360aa10@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x0000000406b130>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ClearForwardedPorts:0x00000004044030>
INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ConnectLibvirt:0x0000000401a280>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000401a320>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000401a370>
INFO warden: Calling OUT action: #<Proc:0x000000040b3458@/opt/vagrant/embedded/gems/gems/vagrant-1.6.3/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x000000034a6c28>
INFO environment: Released process lock: machine-action-f2fc5a49fc05e8b20de9a94244584cff
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO environment: Running hook: environment_unload
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 3 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000004130958>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment