vagrant-libvirt: vagrant up stuck when mounting NFS shared folders

Steps to reproduce

vagrant up
the command is stuck at Mounting NFS shared folders

Expected behaviour

The NFS shared folders should be available and ready

Actual behaviour

vagrant is waiting undefinitely

System configuration

OS/Distro version:: Fedora release 23 (Twenty Three)

Libvirt version: Version : 1.3.3.2

Output of vagrant version; vagrant plugin list:

Installed Version: 1.8.1
Latest Version: 1.9.1
 
To upgrade to the latest version, visit the downloads page and
download and install the latest version of Vagrant from the URL
below:

  http://www.vagrantup.com/downloads.html

If you're curious what changed in the latest release, view the
CHANGELOG below:

  https://github.com/mitchellh/vagrant/blob/v1.9.1/CHANGELOG.md

vagrant-libvirt (0.0.37)

Output of VAGRANT_LOG=debug vagrant ... --provider=libvirt

 INFO global: Vagrant version: 1.8.1
 INFO global: Ruby version: 2.2.5
 INFO global: RubyGems version: 2.4.8
 INFO global: VAGRANT_DEFAULT_PROVIDER="libvirt"
 INFO global: VAGRANT_EXECUTABLE="/usr/share/vagrant/bin/vagrant"
 INFO global: VAGRANT_INSTALLER_EMBEDDED_DIR="/var/lib/vagrant"
 INFO global: VAGRANT_INSTALLER_VERSION="2"
 INFO global: VAGRANT_INTERNAL_BUNDLERIZED="1"
 INFO global: VAGRANT_DETECTED_OS="Linux"
 INFO global: VAGRANT_INSTALLER_ENV="1"
 INFO global: VAGRANT_LOG="debug"
 INFO global: Plugins:
 INFO global:   - builder = 3.2.3
 INFO global:   - bundler = 1.7.8
 INFO global:   - excon = 0.55.0
 INFO global:   - formatador = 0.2.5
 INFO global:   - fog-core = 1.43.0
 INFO global:   - multi_json = 1.12.1
 INFO global:   - fog-json = 1.0.2
 INFO global:   - mini_portile2 = 2.1.0
 INFO global:   - nokogiri = 1.6.8.1
 INFO global:   - fog-xml = 0.1.2
 INFO global:   - json = 2.0.3
 INFO global:   - ruby-libvirt = 0.7.0
 INFO global:   - fog-libvirt = 0.3.0
 INFO global:   - vagrant-libvirt = 0.0.37
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/kernel_v1/plugin.rb
 INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/esxi/plugin.rb
 INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/fedora/plugin.rb
 INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/windows/plugin.rb
 INFO manager: Registered plugin: Windows guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/funtoo/plugin.rb
 INFO manager: Registered plugin: Funtoo guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/arch/plugin.rb
 INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/atomic/plugin.rb
 INFO manager: Registered plugin: Atomic Host guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/debian/plugin.rb
 INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/openbsd/plugin.rb
 INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/slackware/plugin.rb
 INFO manager: Registered plugin: Slackware guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/redhat/plugin.rb
 INFO manager: Registered plugin: Red Hat Enterprise Linux guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/tinycore/plugin.rb
 INFO manager: Registered plugin: TinyCore Linux guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/suse/plugin.rb
 INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/smartos/plugin.rb
 INFO manager: Registered plugin: SmartOS guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/photon/plugin.rb
 INFO manager: Registered plugin: VMware Photon guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/nixos/plugin.rb
 INFO manager: Registered plugin: NixOS guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/netbsd/plugin.rb
 INFO manager: Registered plugin: NetBSD guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/gentoo/plugin.rb
 INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/pld/plugin.rb
 INFO manager: Registered plugin: PLD Linux guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/omnios/plugin.rb
 INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/mint/plugin.rb
 INFO manager: Registered plugin: Mint guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/coreos/plugin.rb
 INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/solaris/plugin.rb
 INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/freebsd/plugin.rb
 INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/ubuntu/plugin.rb
 INFO manager: Registered plugin: Ubuntu guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/solaris11/plugin.rb
 INFO manager: Registered plugin: Solaris 11 guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/linux/plugin.rb
 INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/darwin/plugin.rb
 INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/chef/plugin.rb
 INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/file/plugin.rb
 INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/ansible/plugin.rb
 INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/cfengine/plugin.rb
 INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/docker/plugin.rb
 INFO manager: Registered plugin: docker
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/puppet/plugin.rb
 INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/salt/plugin.rb
 INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/shell/plugin.rb
 INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/kernel_v2/plugin.rb
 INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/provider/plugin.rb
 INFO manager: Registered plugin: provider command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/provision/plugin.rb
 INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/ssh/plugin.rb
 INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/box/plugin.rb
 INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/resume/plugin.rb
 INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/help/plugin.rb
 INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/ssh_config/plugin.rb
 INFO manager: Registered plugin: ssh-config command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/suspend/plugin.rb
 INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/list-commands/plugin.rb
 INFO manager: Registered plugin: list-commands command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/init/plugin.rb
 INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/status/plugin.rb
 INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/reload/plugin.rb
 INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/global-status/plugin.rb
 INFO manager: Registered plugin: global-status command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/halt/plugin.rb
 INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/cap/plugin.rb
 INFO manager: Registered plugin: cap command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/up/plugin.rb
 INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/version/plugin.rb
 INFO manager: Registered plugin: version command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/rdp/plugin.rb
 INFO manager: Registered plugin: rdp command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/package/plugin.rb
 INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/powershell/plugin.rb
 INFO manager: Registered plugin: powershell command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/port/plugin.rb
 INFO manager: Registered plugin: port command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/push/plugin.rb
 INFO manager: Registered plugin: push command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/destroy/plugin.rb
 INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/login/plugin.rb
 INFO manager: Registered plugin: vagrant-login
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/plugin/plugin.rb
 INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/snapshot/plugin.rb
 INFO manager: Registered plugin: snapshot command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/synced_folders/smb/plugin.rb
 INFO manager: Registered plugin: SMB synced folders
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/synced_folders/nfs/plugin.rb
 INFO manager: Registered plugin: NFS synced folders
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/synced_folders/rsync/plugin.rb
 INFO manager: Registered plugin: RSync synced folders
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/providers/virtualbox/plugin.rb
 INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/providers/hyperv/plugin.rb
 INFO manager: Registered plugin: Hyper-V provider
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/providers/docker/plugin.rb
 INFO manager: Registered plugin: docker-provider
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/noop/plugin.rb
 INFO manager: Registered plugin: noop
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/heroku/plugin.rb
 INFO manager: Registered plugin: heroku
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/atlas/plugin.rb
 INFO manager: Registered plugin: atlas
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/local-exec/plugin.rb
 INFO manager: Registered plugin: local-exec
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/ftp/plugin.rb
 INFO manager: Registered plugin: ftp
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/windows/plugin.rb
 INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/arch/plugin.rb
 INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/slackware/plugin.rb
 INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/redhat/plugin.rb
 INFO manager: Registered plugin: Red Hat Enterprise Linux host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/bsd/plugin.rb
 INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/suse/plugin.rb
 INFO manager: Registered plugin: SUSE host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/gentoo/plugin.rb
 INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/freebsd/plugin.rb
 INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/null/plugin.rb
 INFO manager: Registered plugin: null host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/linux/plugin.rb
 INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/darwin/plugin.rb
 INFO manager: Registered plugin: Mac OS X host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/communicators/ssh/plugin.rb
 INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/communicators/winrm/plugin.rb
 INFO manager: Registered plugin: winrm communicator
 INFO global: Loading plugins!
 INFO manager: Registered plugin: libvirt
 INFO vagrant: `vagrant` invoked: ["up", "--provider=libvirt", "--debug"]
DEBUG vagrant: Creating Vagrant environment
 INFO environment: Environment initialized (#<Vagrant::Environment:0x00559c9a544bd8>)
 INFO environment:   - cwd: /home/developer/test
 INFO environment: Home path: /home/developer/.vagrant.d
 INFO environment: Local data path: /home/developer/test/.vagrant
DEBUG environment: Creating: /home/developer/test/.vagrant
 INFO environment: Running hook: environment_plugins_loaded
 INFO runner: Preparing hooks for middleware sequence...
 INFO runner: 2 hooks defined.
 INFO runner: Running action: environment_plugins_loaded #<Vagrant::Action::Builder:0x00559c9a3daba8>
 INFO environment: Running hook: environment_load
 INFO runner: Preparing hooks for middleware sequence...
 INFO runner: 2 hooks defined.
 INFO runner: Running action: environment_load #<Vagrant::Action::Builder:0x00559c9a19c710>
 INFO cli: CLI: [] "up" ["--provider=libvirt"]
DEBUG cli: Invoking command class: VagrantPlugins::CommandUp::Command ["--provider=libvirt"]
DEBUG command: 'Up' each target VM...
 INFO loader: Set :root = ["#<Pathname:/home/developer/test/Vagrantfile>"]
DEBUG loader: Populating proc cache for #<Pathname:/home/developer/test/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/developer/test/Vagrantfile
 INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: root (evaluating)
DEBUG provisioner: Provisioner defined: 
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO host: Autodetecting host type for [#<Vagrant::Environment: /home/developer/test>]
DEBUG host: Trying: arch
DEBUG host: Trying: slackware
DEBUG host: Trying: redhat
 INFO host: Detected: redhat!
DEBUG host: Searching for cap: provider_install_libvirt
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG command: Getting target VMs for command. Arguments:
DEBUG command:  -- names: ["default"]
DEBUG command:  -- options: {:provider=>"libvirt"}
DEBUG command: Finding machine that match name: default
 INFO environment: Getting machine: default (libvirt)
 INFO environment: Uncached load of machine.
 INFO loader: Set "47065546217560_machine_default" = []
 INFO loader: Loading configuration in order: [:home, :root, "47065546217560_machine_default"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO box_collection: Box found: debian/jessie64 (libvirt)
 INFO environment: Running hook: authenticate_box_url
 INFO runner: Preparing hooks for middleware sequence...
 INFO runner: 3 hooks defined.
 INFO runner: Running action: authenticate_box_url #<Vagrant::Action::Builder:0x00559c9a1b4ec8>
 INFO warden: Calling IN action: #<VagrantPlugins::LoginCommand::AddAuthentication:0x00559c9b305060>
DEBUG client: No authentication token in environment or /home/developer/.vagrant.d/data/vagrant_login_token
 INFO warden: Calling OUT action: #<VagrantPlugins::LoginCommand::AddAuthentication:0x00559c9b305060>
 INFO loader: Set :"47065545457760_debian/jessie64_libvirt" = ["#<Pathname:/home/developer/.vagrant.d/boxes/debian-VAGRANTSLASH-jessie64/8.7.0/libvirt/Vagrantfile>"]
DEBUG loader: Populating proc cache for #<Pathname:/home/developer/.vagrant.d/boxes/debian-VAGRANTSLASH-jessie64/8.7.0/libvirt/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/developer/.vagrant.d/boxes/debian-VAGRANTSLASH-jessie64/8.7.0/libvirt/Vagrantfile
 INFO loader: Loading configuration in order: [:"47065545457760_debian/jessie64_libvirt", :home, :root, "47065546217560_machine_default"]
DEBUG loader: Loading from: 47065545457760_debian/jessie64_libvirt (evaluating)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO machine: Initializing machine: default
 INFO machine:   - Provider: VagrantPlugins::ProviderLibvirt::Provider
 INFO machine:   - Box: #<Vagrant::Box:0x00559c9b338190>
 INFO machine:   - Data dir: /home/developer/test/.vagrant/machines/default/libvirt
 INFO machine: New machine ID: nil
 INFO interface: Machine: metadata ["provider", :libvirt, {:target=>:default}]
 INFO command: With machine: default (#<VagrantPlugins::ProviderLibvirt::Provider:0x00559c9b607100 @machine=#<Vagrant::Machine: default (VagrantPlugins::ProviderLibvirt::Provider)>, @cap_logger=#<Log4r::Logger:0x00559c9b606c00 @fullname="vagrant::capability_host::vagrantplugins::providerlibvirt::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrantplugins::providerlibvirt", @parent=#<Log4r::Logger:0x00559c99b41bb8 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00559c99ab54b0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00559c99ab5410>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00559c99a5fe70 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00559c99b41a78 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @cap_host_chain=[[:libvirt, #<#<Class:0x00559c9b6070b0>:0x00559c9b63f8e8>]], @cap_args=[#<Vagrant::Machine: default (VagrantPlugins::ProviderLibvirt::Provider)>], @cap_caps={:virtualbox=>#<Vagrant::Registry:0x00559c9b606f70 @items={:forwarded_ports=>#<Proc:0x00559c9a3bd3c8@/usr/share/vagrant/plugins/providers/virtualbox/plugin.rb:27>, :nic_mac_addresses=>#<Proc:0x00559c9a3bd328@/usr/share/vagrant/plugins/providers/virtualbox/plugin.rb:32>, :public_address=>#<Proc:0x00559c9a3bd300@/usr/share/vagrant/plugins/providers/virtualbox/plugin.rb:37>, :snapshot_list=>#<Proc:0x00559c9a3bd2d8@/usr/share/vagrant/plugins/providers/virtualbox/plugin.rb:42>}, @results_cache={}>, :hyperv=>#<Vagrant::Registry:0x00559c9b606ed0 @items={:public_address=>#<Proc:0x00559c9a3c9308@/usr/share/vagrant/plugins/providers/hyperv/plugin.rb:25>}, @results_cache={}>, :docker=>#<Vagrant::Registry:0x00559c9b606e30 @items={:public_address=>#<Proc:0x00559c9976adc8@/usr/share/vagrant/plugins/providers/docker/plugin.rb:54>, :proxy_machine=>#<Proc:0x00559c9976ab98@/usr/share/vagrant/plugins/providers/docker/plugin.rb:59>}, @results_cache={}>, :libvirt=>#<Vagrant::Registry:0x00559c9b606d90 @items={:nic_mac_addresses=>#<Proc:0x00559c996c2718@/home/developer/.vagrant.d/gems/gems/vagrant-libvirt-0.0.37/lib/vagrant-libvirt/plugin.rb:37>}, @results_cache={}>}>)
 INFO interface: info: Bringing machine 'default' up with 'libvirt' provider...
Bringing machine 'default' 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:0x00559c9ae73ab0> up {:destroy_on_error=>true, :install_provider=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil, :provider=>"libvirt"}
 INFO machine: Calling action: up on provider Libvirt (new)
DEBUG environment: Attempting to acquire process-lock: machine-action-6eb4838b9b208d7c9f1ea56ac8a96d2d
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-6eb4838b9b208d7c9f1ea56ac8a96d2d
 INFO interface: Machine: action ["up", "start", {:target=>:default}]
 INFO runner: Preparing hooks for middleware sequence...
 INFO runner: 2 hooks defined.
 INFO runner: Running action: machine_action_up #<Vagrant::Action::Builder:0x00559c9b77ded0>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00559c9acba408>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00559c9acb92d8>
 INFO runner: Preparing hooks for middleware sequence...
 INFO runner: 2 hooks defined.
 INFO runner: Running action: machine_action_up #<Vagrant::Action::Builder:0x007fa84c093c28>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x007fa84c091220>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x007fa84c091220>
 INFO driver: Connecting to Libvirt (qemu:///system?no_verify=1&keyfile=/home/developer/.ssh/id_rsa) ...
 INFO runner: Preparing hooks for middleware sequence...
 INFO runner: 2 hooks defined.
 INFO runner: Running action: machine_action_up #<Vagrant::Action::Warden:0x007fa84c5712b8>
 INFO warden: Calling IN action: #<Proc:0x00559c9a40e728@/usr/share/vagrant/lib/vagrant/action/warden.rb:94 (lambda)>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::SetNameOfDomain:0x007fa84c571240>
 INFO set_name_of_domain: Looking for domain test_default through list [  <Fog::Compute::Libvirt::Server
    id="7c1984ea-a877-4bad-a340-8b38e3e38e58",
    cpus=2,
    cputime=0,
    os_type="hvm",
    memory_size=1536000,
    max_memory_size=1536000,
    name="Windows_7_x64",
    arch="x86_64",
    persistent=true,
    domain_type="kvm",
    uuid="7c1984ea-a877-4bad-a340-8b38e3e38e58",
    autostart=false,
    nics=[    <Fog::Compute::Libvirt::Nic
      mac="52:54:00:71:b5:10",
      id=nil,
      type="network",
      network="default",
      bridge=nil,
      model="e1000"
    >],
    volumes=[    <Fog::Compute::Libvirt::Volume
      id="/home/developer/vm/Windows_7_x64.qcow2",
      pool_name="default",
      key="/home/developer/vm/Windows_7_x64.qcow2",
      name="Windows_7_x64.qcow2",
      path="/home/developer/vm/Windows_7_x64.qcow2",
      capacity=58,
      allocation=27,
      format_type="qcow2",
      backing_volume=nil
    >],
    active=false,
    boot_order=["hd"],
    display={:type=>"spice"},
    state="shutoff"
  >,   <Fog::Compute::Libvirt::Server
    id="197b918d-3c33-4993-a4c7-2c5813a86206",
    cpus=1,
    cputime=0,
    os_type="hvm",
    memory_size=524288,
    max_memory_size=524288,
    name="nitro_win7x64",
    arch="x86_64",
    persistent=true,
    domain_type="kvm",
    uuid="197b918d-3c33-4993-a4c7-2c5813a86206",
    autostart=false,
    nics=[    <Fog::Compute::Libvirt::Nic
      mac="52:54:00:19:f6:b9",
      id=nil,
      type="network",
      network="default",
      bridge=nil,
      model="e1000"
    >],
    volumes=[    <Fog::Compute::Libvirt::Volume
      id="/home/developer/fsecure/kvm-vmi/nitro/tests/images/win7x64.qcow2",
      pool_name="nitro",
      key="/home/developer/fsecure/kvm-vmi/nitro/tests/images/win7x64.qcow2",
      name="win7x64.qcow2",
      path="/home/developer/fsecure/kvm-vmi/nitro/tests/images/win7x64.qcow2",
      capacity=60,
      allocation=9,
      format_type="qcow2",
      backing_volume=nil
    >],
    active=false,
    boot_order=["hd"],
    display={:type=>"spice"},
    state="shutoff"
  >]
 INFO set_name_of_domain: Looking for domain test_default
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::HandleStoragePool:0x007fa84c539980>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::HandleBox:0x007fa84c4ea718>
 INFO handle_box: Machine already has box. HandleBox will not run.
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::HandleBoxImage:0x007fa84c4b3768>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateDomainVolume:0x007fa84c491820>
 INFO interface: info: Creating image (snapshot of base box volume).
 INFO interface: info: ==> default: Creating image (snapshot of base box volume).
==> default: Creating image (snapshot of base box volume).
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateDomain:0x007fa84c410630>
 INFO interface: info: Creating domain with the following settings...
 INFO interface: info: ==> default: Creating domain with the following settings...
==> default: Creating domain with the following settings...
 INFO interface: info:  -- Name:              test_default
 INFO interface: info: ==> default:  -- Name:              test_default
==> default:  -- Name:              test_default
 INFO interface: info:  -- Domain type:       kvm
 INFO interface: info: ==> default:  -- Domain type:       kvm
==> default:  -- Domain type:       kvm
 INFO interface: info:  -- Cpus:              1
 INFO interface: info: ==> default:  -- Cpus:              1
==> default:  -- Cpus:              1
 INFO interface: info:  -- Memory:            512M
 INFO interface: info: ==> default:  -- Memory:            512M
==> default:  -- Memory:            512M
 INFO interface: info:  -- Management MAC:    
 INFO interface: info: ==> default:  -- Management MAC:    
==> default:  -- Management MAC:    
 INFO interface: info:  -- Loader:            
 INFO interface: info: ==> default:  -- Loader:            
==> default:  -- Loader:            
 INFO interface: info:  -- Base box:          debian/jessie64
 INFO interface: info: ==> default:  -- Base box:          debian/jessie64
==> default:  -- Base box:          debian/jessie64
 INFO interface: info:  -- Storage pool:      default
 INFO interface: info: ==> default:  -- Storage pool:      default
==> default:  -- Storage pool:      default
 INFO interface: info:  -- Image:             /home/developer/vm/test_default.img (10G)
 INFO interface: info: ==> default:  -- Image:             /home/developer/vm/test_default.img (10G)
==> default:  -- Image:             /home/developer/vm/test_default.img (10G)
 INFO interface: info:  -- Volume Cache:      default
 INFO interface: info: ==> default:  -- Volume Cache:      default
==> default:  -- Volume Cache:      default
 INFO interface: info:  -- Kernel:            
 INFO interface: info: ==> default:  -- Kernel:            
==> default:  -- Kernel:            
 INFO interface: info:  -- Initrd:            
 INFO interface: info: ==> default:  -- Initrd:            
==> default:  -- Initrd:            
 INFO interface: info:  -- Graphics Type:     vnc
 INFO interface: info: ==> default:  -- Graphics Type:     vnc
==> default:  -- Graphics Type:     vnc
 INFO interface: info:  -- Graphics Port:     5900
 INFO interface: info: ==> default:  -- Graphics Port:     5900
==> default:  -- Graphics Port:     5900
 INFO interface: info:  -- Graphics IP:       127.0.0.1
 INFO interface: info: ==> default:  -- Graphics IP:       127.0.0.1
==> default:  -- Graphics IP:       127.0.0.1
 INFO interface: info:  -- Graphics Password: Not defined
 INFO interface: info: ==> default:  -- Graphics Password: Not defined
==> default:  -- Graphics Password: Not defined
 INFO interface: info:  -- Video Type:        cirrus
 INFO interface: info: ==> default:  -- Video Type:        cirrus
==> default:  -- Video Type:        cirrus
 INFO interface: info:  -- Video VRAM:        9216
 INFO interface: info: ==> default:  -- Video VRAM:        9216
==> default:  -- Video VRAM:        9216
 INFO interface: info:  -- Keymap:            en-us
 INFO interface: info: ==> default:  -- Keymap:            en-us
==> default:  -- Keymap:            en-us
 INFO interface: info:  -- TPM Path:          
 INFO interface: info: ==> default:  -- TPM Path:          
==> default:  -- TPM Path:          
 INFO interface: info:  -- INPUT:             type=mouse, bus=ps2
 INFO interface: info: ==> default:  -- INPUT:             type=mouse, bus=ps2
==> default:  -- INPUT:             type=mouse, bus=ps2
 INFO interface: info:  -- Command line : 
 INFO interface: info: ==> default:  -- Command line : 
==> default:  -- Command line : 
 INFO machine: New machine ID: "955df75a-9a25-4de0-a775-ae8c849729af"
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Provision:0x007fa84c3d6390>
 INFO provision: Checking provisioner sentinel file...
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSValidIds:0x007fa84c396948>
 INFO warden: Calling IN action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x007fa84c34f570>
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
 INFO nfs: NFS pruning. Valid IDs: ["7c1984ea-a877-4bad-a340-8b38e3e38e58", "955df75a-9a25-4de0-a775-ae8c849729af", "197b918d-3c33-4993-a4c7-2c5813a86206"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
 INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/developer/test>, #<Vagrant::UI::Prefixed:0x00559c9b5c74b0 @logger=#<Log4r::Logger:0x00559c9b5c7460 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00559c99b41bb8 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00559c99ab54b0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00559c99ab5410>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00559c99a5fe70 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00559c99b41a78 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @prefix=:default, @ui=#<Vagrant::UI::Basic:0x00559c9a544958 @logger=#<Log4r::Logger:0x00559c9a544908 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00559c99b41bb8 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00559c99ab54b0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00559c99ab5410>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00559c99a5fe70 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00559c99b41a78 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @lock=#<Mutex:0x00559c9a4f8490>>>, ["7c1984ea-a877-4bad-a340-8b38e3e38e58", "955df75a-9a25-4de0-a775-ae8c849729af", "197b918d-3c33-4993-a4c7-2c5813a86206"]] (redhat)
 INFO linux: Pruning invalid NFS entries...
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x007fa84c303350>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
 INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/developer/test>] (redhat)
 INFO synced_folder_cleanup: Invoking synced folder cleanup for: nfs
 INFO nfs: NFS pruning. Valid IDs: ["7c1984ea-a877-4bad-a340-8b38e3e38e58", "955df75a-9a25-4de0-a775-ae8c849729af", "197b918d-3c33-4993-a4c7-2c5813a86206"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
 INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/developer/test>, #<Vagrant::UI::Prefixed:0x00559c9b5c74b0 @logger=#<Log4r::Logger:0x00559c9b5c7460 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00559c99b41bb8 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00559c99ab54b0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00559c99ab5410>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00559c99a5fe70 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00559c99b41a78 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @prefix=:default, @ui=#<Vagrant::UI::Basic:0x00559c9a544958 @logger=#<Log4r::Logger:0x00559c9a544908 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00559c99b41bb8 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00559c99ab54b0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00559c99ab5410>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00559c99a5fe70 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00559c99b41a78 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @lock=#<Mutex:0x00559c9a4f8490>>>, ["7c1984ea-a877-4bad-a340-8b38e3e38e58", "955df75a-9a25-4de0-a775-ae8c849729af", "197b918d-3c33-4993-a4c7-2c5813a86206"]] (redhat)
 INFO linux: Pruning invalid NFS entries...
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolders:0x007fa84c2bb3e8>
 INFO synced_folders: SyncedFolders loading from cache: false
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
 INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/developer/test>] (redhat)
 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::PrepareNFSSettings:0x007fa84c276b30>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ShareFolders:0x007fa84c2367d8>
 INFO interface: info: Creating shared folders metadata...
 INFO interface: info: ==> default: Creating shared folders metadata...
==> default: Creating shared folders metadata...
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworks:0x007fa84c1f1b60>
 INFO create_networks: Using vagrant-libvirt at 192.168.121.0/24 as the management network nat is the mode
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", :guest_ipv6=>"yes", :autostart=>false}
DEBUG create_networks: looking up network with ip == 192.168.121.0
DEBUG create_networks: Checking that network name does not clash with ip
DEBUG create_networks: looking up network named vagrant-libvirt
DEBUG create_networks: generating name for bridge
DEBUG create_networks: looking up bridge named virbr0
DEBUG create_networks: looking up bridge named virbr1
DEBUG create_networks: found available bridge name virbr1
DEBUG create_networks: created network
 INFO create_networks: Saving information about created network vagrant-libvirt, UUID=0e1210d2-9382-490a-949e-1220868149bf to file /home/developer/test/.vagrant/machines/default/libvirt/created_networks.
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworkInterfaces:0x00559c9ac215a0>
 INFO create_network_interfaces: Using vagrant-libvirt at 192.168.121.0/24 as the management network nat is the mode
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::SetBootOrder:0x00559c9aba3740>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x00559c9ab25a48>
 INFO interface: info: Starting domain.
 INFO interface: info: ==> default: Starting domain.
==> default: Starting domain.
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::WaitTillUp:0x00559c9aac7470>
DEBUG wait_till_up: Searching for IP for MAC address: 52:54:00:de:b7:60
 INFO interface: info: Waiting for domain to get an IP address...
 INFO interface: info: ==> default: Waiting for domain to get an IP address...
==> default: 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.132
 INFO wait_till_up: Time for getting IP: 13.144825220108032
 INFO interface: info: Waiting for SSH to become available...
 INFO interface: info: ==> default: Waiting for SSH to become available...
==> default: Waiting for SSH to become available...
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Checking key permissions: /home/developer/.vagrant.d/insecure_private_key
 INFO ssh: Attempting SSH connection...
 INFO ssh: Attempting to connect to SSH...
 INFO ssh:   - Host: 192.168.121.132
 INFO ssh:   - Port: 22
 INFO ssh:   - Username: vagrant
 INFO ssh:   - Password? false
 INFO ssh:   - Key Path: ["/home/developer/.vagrant.d/insecure_private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2017-02-13T13:22:59.637885 #123407] DEBUG -- net.ssh.transport.session[2ace4d79d330]: establishing connection to 192.168.121.132:22

DEBUG ssh: == Net-SSH connection debug-level log END ==
 INFO retryable: Retryable exception raised: #<Errno::EHOSTUNREACH: No route to host - connect(2) for 192.168.121.132:22>
 INFO ssh: Attempting to connect to SSH...
 INFO ssh:   - Host: 192.168.121.132
 INFO ssh:   - Port: 22
 INFO ssh:   - Username: vagrant
 INFO ssh:   - Password? false
 INFO ssh:   - Key Path: ["/home/developer/.vagrant.d/insecure_private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2017-02-13T13:22:59.932571 #123407] DEBUG -- net.ssh.transport.session[2ace4d7532e4]: establishing connection to 192.168.121.132:22
D, [2017-02-13T13:22:59.933292 #123407] DEBUG -- net.ssh.transport.session[2ace4d7532e4]: connection established
I, [2017-02-13T13:22:59.933448 #123407]  INFO -- net.ssh.transport.server_version[2ace4d75222c]: negotiating protocol version
D, [2017-02-13T13:22:59.933490 #123407] DEBUG -- net.ssh.transport.server_version[2ace4d75222c]: local is `SSH-2.0-Ruby/Net::SSH_4.0.1 x86_64-linux'
D, [2017-02-13T13:22:59.939830 #123407] DEBUG -- net.ssh.transport.server_version[2ace4d75222c]: remote is `SSH-2.0-OpenSSH_6.7p1 Debian-5+deb8u3'
I, [2017-02-13T13:22:59.940178 #123407]  INFO -- net.ssh.transport.algorithms[2ace4d74caac]: sending KEXINIT
D, [2017-02-13T13:22:59.940484 #123407] DEBUG -- socket[2ace4d752d80]: queueing packet nr 0 type 20 len 1156
D, [2017-02-13T13:22:59.940603 #123407] DEBUG -- socket[2ace4d752d80]: sent 1160 bytes
D, [2017-02-13T13:22:59.940703 #123407] DEBUG -- socket[2ace4d752d80]: read 952 bytes
D, [2017-02-13T13:22:59.940823 #123407] DEBUG -- socket[2ace4d752d80]: received packet nr 0 type 20 len 948
I, [2017-02-13T13:22:59.940905 #123407]  INFO -- net.ssh.transport.algorithms[2ace4d74caac]: got KEXINIT from server
I, [2017-02-13T13:22:59.941031 #123407]  INFO -- net.ssh.transport.algorithms[2ace4d74caac]: negotiating algorithms
D, [2017-02-13T13:22:59.941191 #123407] DEBUG -- net.ssh.transport.algorithms[2ace4d74caac]: negotiated:
* kex: diffie-hellman-group14-sha1
* host_key: ssh-rsa
* encryption_server: aes128-ctr
* encryption_client: aes128-ctr
* hmac_client: hmac-sha1
* hmac_server: hmac-sha1
* compression_client: none
* compression_server: none
* language_client: 
* language_server: 
D, [2017-02-13T13:22:59.941226 #123407] DEBUG -- net.ssh.transport.algorithms[2ace4d74caac]: exchanging keys
D, [2017-02-13T13:22:59.949449 #123407] DEBUG -- socket[2ace4d752d80]: queueing packet nr 1 type 30 len 268
D, [2017-02-13T13:22:59.949562 #123407] DEBUG -- socket[2ace4d752d80]: sent 272 bytes
D, [2017-02-13T13:22:59.983072 #123407] DEBUG -- socket[2ace4d752d80]: read 848 bytes
D, [2017-02-13T13:22:59.983209 #123407] DEBUG -- socket[2ace4d752d80]: received packet nr 1 type 31 len 828
D, [2017-02-13T13:22:59.984023 #123407] DEBUG -- socket[2ace4d752d80]: queueing packet nr 2 type 21 len 20
D, [2017-02-13T13:22:59.984130 #123407] DEBUG -- socket[2ace4d752d80]: sent 24 bytes
D, [2017-02-13T13:22:59.984208 #123407] DEBUG -- socket[2ace4d752d80]: received packet nr 2 type 21 len 12
D, [2017-02-13T13:22:59.984596 #123407] DEBUG -- net.ssh.authentication.session[2ace4d6ba0f8]: beginning authentication of `vagrant'
D, [2017-02-13T13:22:59.984713 #123407] DEBUG -- socket[2ace4d752d80]: queueing packet nr 3 type 5 len 28
D, [2017-02-13T13:22:59.984744 #123407] DEBUG -- socket[2ace4d752d80]: sent 52 bytes
D, [2017-02-13T13:23:00.024341 #123407] DEBUG -- socket[2ace4d752d80]: read 52 bytes
D, [2017-02-13T13:23:00.024560 #123407] DEBUG -- socket[2ace4d752d80]: received packet nr 3 type 6 len 28
D, [2017-02-13T13:23:00.024681 #123407] DEBUG -- net.ssh.authentication.session[2ace4d6ba0f8]: trying none
D, [2017-02-13T13:23:00.024823 #123407] DEBUG -- socket[2ace4d752d80]: queueing packet nr 4 type 50 len 44
D, [2017-02-13T13:23:00.024894 #123407] DEBUG -- socket[2ace4d752d80]: sent 68 bytes
D, [2017-02-13T13:23:00.025534 #123407] DEBUG -- socket[2ace4d752d80]: read 52 bytes
D, [2017-02-13T13:23:00.025688 #123407] DEBUG -- socket[2ace4d752d80]: received packet nr 4 type 51 len 28
D, [2017-02-13T13:23:00.025801 #123407] DEBUG -- net.ssh.authentication.session[2ace4d6ba0f8]: allowed methods: publickey
D, [2017-02-13T13:23:00.025876 #123407] DEBUG -- net.ssh.authentication.methods.none[2ace4d6af7c0]: none failed
D, [2017-02-13T13:23:00.025954 #123407] DEBUG -- net.ssh.authentication.session[2ace4d6ba0f8]: trying publickey
D, [2017-02-13T13:23:00.026268 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d6a5b80]: connecting to ssh-agent
D, [2017-02-13T13:23:00.026735 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d6a5b80]: sending agent request 1 len 44
D, [2017-02-13T13:23:00.027109 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d6a5b80]: received agent packet 2 len 5
D, [2017-02-13T13:23:00.027176 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d6a5b80]: sending agent request 11 len 0
D, [2017-02-13T13:23:00.028166 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d6a5b80]: received agent packet 12 len 579
D, [2017-02-13T13:23:00.028582 #123407] DEBUG -- net.ssh.authentication.methods.publickey[2ace4d6aa144]: trying publickey (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
D, [2017-02-13T13:23:00.028970 #123407] DEBUG -- socket[2ace4d752d80]: queueing packet nr 5 type 50 len 348
D, [2017-02-13T13:23:00.029103 #123407] DEBUG -- socket[2ace4d752d80]: sent 372 bytes
D, [2017-02-13T13:23:00.033442 #123407] DEBUG -- socket[2ace4d752d80]: read 324 bytes
D, [2017-02-13T13:23:00.033686 #123407] DEBUG -- socket[2ace4d752d80]: received packet nr 5 type 60 len 300
D, [2017-02-13T13:23:00.035727 #123407] DEBUG -- socket[2ace4d752d80]: queueing packet nr 6 type 50 len 620
D, [2017-02-13T13:23:00.035836 #123407] DEBUG -- socket[2ace4d752d80]: sent 644 bytes
D, [2017-02-13T13:23:00.037958 #123407] DEBUG -- socket[2ace4d752d80]: read 36 bytes
D, [2017-02-13T13:23:00.038179 #123407] DEBUG -- socket[2ace4d752d80]: received packet nr 6 type 52 len 12
D, [2017-02-13T13:23:00.038264 #123407] DEBUG -- net.ssh.authentication.methods.publickey[2ace4d6aa144]: 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
DEBUG ssh: Checking key permissions: /home/developer/.vagrant.d/insecure_private_key
 INFO interface: detail: 
Vagrant insecure key detected. Vagrant will automatically replace
this with a newly generated keypair for better security.
 INFO interface: detail:     default: 
    default: Vagrant insecure key detected. Vagrant will automatically replace
    default: this with a newly generated keypair for better security.
    default: 
    default: Vagrant insecure key detected. Vagrant will automatically replace
    default: this with a newly generated keypair for better security.
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: default (VagrantPlugins::ProviderLibvirt::Provider)>]
DEBUG guest: Trying: atomic
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: grep 'ostree=' /proc/cmdline (sudo=false)
DEBUG ssh: Exit status: 1
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: fedora
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: grep 'Fedora release' /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: 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: ubuntu
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: [ -x /usr/bin/lsb_release ] && /usr/bin/lsb_release -i 2>/dev/null | grep Ubuntu (sudo=false)
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: funtoo
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: grep Funtoo /etc/gentoo-release (sudo=false)
DEBUG ssh: stderr: grep: 
DEBUG ssh: stderr: /etc/gentoo-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr: 

DEBUG ssh: Exit status: 2
DEBUG guest: Trying: arch
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: cat /etc/arch-release (sudo=false)
DEBUG ssh: stderr: cat: 
DEBUG ssh: stderr: /etc/arch-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr: 

DEBUG ssh: Exit status: 1
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 8 \n \l

DEBUG ssh: Exit status: 0
 INFO guest: Detected: debian!
DEBUG guest: Searching for cap: insert_public_key
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: insert_public_key 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: remove_public_key
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: remove_public_key in linux
 INFO ssh: Inserting key to avoid password: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDHPSR/Fxv5UZMtEdtap2tCrZ/hgiEe8+ySYLK6sPb/wUJ09wZwQ5LMNNmGDffMDdFSPNUf2KKe1F6KzeyP0K1+KSpoxUVE38Vpeq9U43+QSvznttaCJLaXUsK6sta+16C4pvAFsyPb55c/rjnMLwGQYRY+rI/mATAAJx3GfLuXA5nJlcnTAFgao9Wg/AuklZbeKlh0FLV2SzHZY15yBkfXV8UDNN02kDziGcro/b6L18T+l64Dzc1XMYYCy5DlkY55E/KJs54NG7YQQzZgyK2I/QUw0pbXgCkIeRGivN7JGlAZRBR7fOwJliqpnjVT4t3ot3OoIQwkjGbMtSt1OGj/ vagrant
 INFO interface: detail: 
Inserting generated public key within guest...
 INFO interface: detail:     default: 
    default: Inserting generated public key within guest...
    default: 
    default: Inserting generated public key within guest...
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: insert_public_key
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: insert_public_key in linux
 INFO guest: Execute capability: insert_public_key [#<Vagrant::Machine: default (VagrantPlugins::ProviderLibvirt::Provider)>, "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDHPSR/Fxv5UZMtEdtap2tCrZ/hgiEe8+ySYLK6sPb/wUJ09wZwQ5LMNNmGDffMDdFSPNUf2KKe1F6KzeyP0K1+KSpoxUVE38Vpeq9U43+QSvznttaCJLaXUsK6sta+16C4pvAFsyPb55c/rjnMLwGQYRY+rI/mATAAJx3GfLuXA5nJlcnTAFgao9Wg/AuklZbeKlh0FLV2SzHZY15yBkfXV8UDNN02kDziGcro/b6L18T+l64Dzc1XMYYCy5DlkY55E/KJs54NG7YQQzZgyK2I/QUw0pbXgCkIeRGivN7JGlAZRBR7fOwJliqpnjVT4t3ot3OoIQwkjGbMtSt1OGj/ vagrant"] (debian)
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: mkdir -p ~/.ssh (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: chmod 0700 ~/.ssh (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: printf 'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDHPSR/Fxv5UZMtEdtap2tCrZ/hgiEe8+ySYLK6sPb/wUJ09wZwQ5LMNNmGDffMDdFSPNUf2KKe1F6KzeyP0K1+KSpoxUVE38Vpeq9U43+QSvznttaCJLaXUsK6sta+16C4pvAFsyPb55c/rjnMLwGQYRY+rI/mATAAJx3GfLuXA5nJlcnTAFgao9Wg/AuklZbeKlh0FLV2SzHZY15yBkfXV8UDNN02kDziGcro/b6L18T+l64Dzc1XMYYCy5DlkY55E/KJs54NG7YQQzZgyK2I/QUw0pbXgCkIeRGivN7JGlAZRBR7fOwJliqpnjVT4t3ot3OoIQwkjGbMtSt1OGj/ vagrant\n' >> ~/.ssh/authorized_keys (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: chmod 0600 ~/.ssh/authorized_keys (sudo=false)
DEBUG ssh: Exit status: 0
 INFO interface: detail: Removing insecure key from the guest if it's present...
 INFO interface: detail:     default: Removing insecure key from the guest if it's present...
    default: Removing insecure key from the guest if it's present...
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: remove_public_key
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: remove_public_key in linux
 INFO guest: Execute capability: remove_public_key [#<Vagrant::Machine: default (VagrantPlugins::ProviderLibvirt::Provider)>, "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA6NF8iallvQVp22WDkTkyrtvp9eWW6A8YVr+kz4TjGYe7gHzIw+niNltGEFHzD8+v1I2YJ6oXevct1YeS0o9HZyN1Q9qgCgzUFtdOKLv6IedplqoPkcmF0aYet2PkEDo3MlTBckFXPITAMzF8dJSIFo9D8HfdOV0IAdx4O7PtixWKn5y2hMNG0zQPyUecp4pzC6kivAIhyfHilFR61RGL+GPXQ2MWZWFYbAGjyiYJnAmCP3NOTd0jMZEnDkbUvxhMmBYSdETk1rRgm+R4LOzFUGaHqHDLKLX+FIPKcF96hrucXzcWyLbIbEgE98OHlnVYCzRdK8jlqm8tehUc9c9WhQ== vagrant insecure public key"] (debian)
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: test -f ~/.ssh/authorized_keys (sudo=false)
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: sed -e '/^.*ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA6NF8iallvQVp22WDkTkyrtvp9eWW6A8YVr+kz4TjGYe7gHzIw+niNltGEFHzD8+v1I2YJ6oXevct1YeS0o9HZyN1Q9qgCgzUFtdOKLv6IedplqoPkcmF0aYet2PkEDo3MlTBckFXPITAMzF8dJSIFo9D8HfdOV0IAdx4O7PtixWKn5y2hMNG0zQPyUecp4pzC6kivAIhyfHilFR61RGL+GPXQ2MWZWFYbAGjyiYJnAmCP3NOTd0jMZEnDkbUvxhMmBYSdETk1rRgm+R4LOzFUGaHqHDLKLX+FIPKcF96hrucXzcWyLbIbEgE98OHlnVYCzRdK8jlqm8tehUc9c9WhQ== vagrant insecure public key.*$/d' ~/.ssh/authorized_keys > ~/.ssh/authorized_keys.new
mv ~/.ssh/authorized_keys.new ~/.ssh/authorized_keys
chmod 600 ~/.ssh/authorized_keys
 (sudo=false)
DEBUG ssh: Exit status: 0
 INFO interface: detail: Key inserted! Disconnecting and reconnecting using new SSH key...
 INFO interface: detail:     default: Key inserted! Disconnecting and reconnecting using new SSH key...
    default: Key inserted! Disconnecting and reconnecting using new SSH key...
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Checking key permissions: /home/developer/test/.vagrant/machines/default/libvirt/private_key
 INFO ssh: Attempting to correct key permissions to 0600
 INFO ssh: Attempting SSH connection...
 INFO ssh: Attempting to connect to SSH...
 INFO ssh:   - Host: 192.168.121.132
 INFO ssh:   - Port: 22
 INFO ssh:   - Username: vagrant
 INFO ssh:   - Password? false
 INFO ssh:   - Key Path: ["/home/developer/test/.vagrant/machines/default/libvirt/private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2017-02-13T13:23:00.634175 #123407] DEBUG -- net.ssh.transport.session[2ace4d9e5ee4]: establishing connection to 192.168.121.132:22
D, [2017-02-13T13:23:00.635270 #123407] DEBUG -- net.ssh.transport.session[2ace4d9e5ee4]: connection established
I, [2017-02-13T13:23:00.635375 #123407]  INFO -- net.ssh.transport.server_version[2ace4d9e5624]: negotiating protocol version
D, [2017-02-13T13:23:00.635409 #123407] DEBUG -- net.ssh.transport.server_version[2ace4d9e5624]: local is `SSH-2.0-Ruby/Net::SSH_4.0.1 x86_64-linux'
D, [2017-02-13T13:23:00.640201 #123407] DEBUG -- net.ssh.transport.server_version[2ace4d9e5624]: remote is `SSH-2.0-OpenSSH_6.7p1 Debian-5+deb8u3'
I, [2017-02-13T13:23:00.640531 #123407]  INFO -- net.ssh.transport.algorithms[2ace4d9e479c]: sending KEXINIT
D, [2017-02-13T13:23:00.640895 #123407] DEBUG -- socket[2ace4d9e5c14]: queueing packet nr 0 type 20 len 1156
D, [2017-02-13T13:23:00.640995 #123407] DEBUG -- socket[2ace4d9e5c14]: sent 1160 bytes
D, [2017-02-13T13:23:00.641085 #123407] DEBUG -- socket[2ace4d9e5c14]: read 952 bytes
D, [2017-02-13T13:23:00.641190 #123407] DEBUG -- socket[2ace4d9e5c14]: received packet nr 0 type 20 len 948
I, [2017-02-13T13:23:00.641242 #123407]  INFO -- net.ssh.transport.algorithms[2ace4d9e479c]: got KEXINIT from server
I, [2017-02-13T13:23:00.641385 #123407]  INFO -- net.ssh.transport.algorithms[2ace4d9e479c]: negotiating algorithms
D, [2017-02-13T13:23:00.641506 #123407] DEBUG -- net.ssh.transport.algorithms[2ace4d9e479c]: negotiated:
* kex: diffie-hellman-group14-sha1
* host_key: ssh-rsa
* encryption_server: aes128-ctr
* encryption_client: aes128-ctr
* hmac_client: hmac-sha1
* hmac_server: hmac-sha1
* compression_client: none
* compression_server: none
* language_client: 
* language_server: 
D, [2017-02-13T13:23:00.641530 #123407] DEBUG -- net.ssh.transport.algorithms[2ace4d9e479c]: exchanging keys
D, [2017-02-13T13:23:00.654643 #123407] DEBUG -- socket[2ace4d9e5c14]: queueing packet nr 1 type 30 len 268
D, [2017-02-13T13:23:00.654843 #123407] DEBUG -- socket[2ace4d9e5c14]: sent 272 bytes
D, [2017-02-13T13:23:00.684227 #123407] DEBUG -- socket[2ace4d9e5c14]: read 848 bytes
D, [2017-02-13T13:23:00.684452 #123407] DEBUG -- socket[2ace4d9e5c14]: received packet nr 1 type 31 len 828
D, [2017-02-13T13:23:00.685773 #123407] DEBUG -- socket[2ace4d9e5c14]: queueing packet nr 2 type 21 len 20
D, [2017-02-13T13:23:00.685908 #123407] DEBUG -- socket[2ace4d9e5c14]: sent 24 bytes
D, [2017-02-13T13:23:00.686024 #123407] DEBUG -- socket[2ace4d9e5c14]: received packet nr 2 type 21 len 12
D, [2017-02-13T13:23:00.686631 #123407] DEBUG -- net.ssh.authentication.session[2ace4d95ee80]: beginning authentication of `vagrant'
D, [2017-02-13T13:23:00.686757 #123407] DEBUG -- socket[2ace4d9e5c14]: queueing packet nr 3 type 5 len 28
D, [2017-02-13T13:23:00.686794 #123407] DEBUG -- socket[2ace4d9e5c14]: sent 52 bytes
D, [2017-02-13T13:23:00.724840 #123407] DEBUG -- socket[2ace4d9e5c14]: read 52 bytes
D, [2017-02-13T13:23:00.725014 #123407] DEBUG -- socket[2ace4d9e5c14]: received packet nr 3 type 6 len 28
D, [2017-02-13T13:23:00.725101 #123407] DEBUG -- net.ssh.authentication.session[2ace4d95ee80]: trying none
D, [2017-02-13T13:23:00.725421 #123407] DEBUG -- socket[2ace4d9e5c14]: queueing packet nr 4 type 50 len 44
D, [2017-02-13T13:23:00.725514 #123407] DEBUG -- socket[2ace4d9e5c14]: sent 68 bytes
D, [2017-02-13T13:23:00.727113 #123407] DEBUG -- socket[2ace4d9e5c14]: read 52 bytes
D, [2017-02-13T13:23:00.727307 #123407] DEBUG -- socket[2ace4d9e5c14]: received packet nr 4 type 51 len 28
D, [2017-02-13T13:23:00.727401 #123407] DEBUG -- net.ssh.authentication.session[2ace4d95ee80]: allowed methods: publickey
D, [2017-02-13T13:23:00.727479 #123407] DEBUG -- net.ssh.authentication.methods.none[2ace4d95b244]: none failed
D, [2017-02-13T13:23:00.727563 #123407] DEBUG -- net.ssh.authentication.session[2ace4d95ee80]: trying publickey
D, [2017-02-13T13:23:00.727850 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d958760]: connecting to ssh-agent
D, [2017-02-13T13:23:00.728179 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d958760]: sending agent request 1 len 44
D, [2017-02-13T13:23:00.728608 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d958760]: received agent packet 2 len 5
D, [2017-02-13T13:23:00.728716 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d958760]: sending agent request 11 len 0
D, [2017-02-13T13:23:00.729494 #123407] DEBUG -- net.ssh.authentication.agent[2ace4d958760]: received agent packet 12 len 579
D, [2017-02-13T13:23:00.729984 #123407] DEBUG -- net.ssh.authentication.methods.publickey[2ace4d958abc]: trying publickey (6d:81:8b:1c:1c:48:4d:c3:f1:90:4c:cb:2e:96:8f:8a)
D, [2017-02-13T13:23:00.730441 #123407] DEBUG -- socket[2ace4d9e5c14]: queueing packet nr 5 type 50 len 348
D, [2017-02-13T13:23:00.730605 #123407] DEBUG -- socket[2ace4d9e5c14]: sent 372 bytes
D, [2017-02-13T13:23:00.731347 #123407] DEBUG -- socket[2ace4d9e5c14]: read 324 bytes
D, [2017-02-13T13:23:00.731623 #123407] DEBUG -- socket[2ace4d9e5c14]: received packet nr 5 type 60 len 300
D, [2017-02-13T13:23:00.733510 #123407] DEBUG -- socket[2ace4d9e5c14]: queueing packet nr 6 type 50 len 620
D, [2017-02-13T13:23:00.733666 #123407] DEBUG -- socket[2ace4d9e5c14]: sent 644 bytes
D, [2017-02-13T13:23:00.735754 #123407] DEBUG -- socket[2ace4d9e5c14]: read 36 bytes
D, [2017-02-13T13:23:00.735999 #123407] DEBUG -- socket[2ace4d9e5c14]: received packet nr 6 type 52 len 12
D, [2017-02-13T13:23:00.736138 #123407] DEBUG -- net.ssh.authentication.methods.publickey[2ace4d958abc]: publickey succeeded (6d:81:8b:1c:1c:48:4d:c3:f1:90:4c:cb:2e:96:8f:8a)

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 wait_till_up: Time for SSH ready: 1.1927213668823242
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ForwardPorts:0x00559c9aa6f900>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SetHostname:0x00559c9a4fb4b0>
 INFO warden: Calling IN action: #<Proc:0x00559c9a4fb3e8@/usr/share/vagrant/lib/vagrant/action/warden.rb:94 (lambda)>
 INFO warden: Calling OUT action: #<Proc:0x00559c9a4fb3e8@/usr/share/vagrant/lib/vagrant/action/warden.rb:94 (lambda)>
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::SetHostname:0x00559c9a4fb4b0>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ForwardPorts:0x00559c9aa6f900>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::WaitTillUp:0x00559c9aac7470>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x00559c9ab25a48>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::SetBootOrder:0x00559c9aba3740>
 INFO interface: info: Configuring and enabling network interfaces...
 INFO interface: info: ==> default: Configuring and enabling network interfaces...
==> default: 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: default (VagrantPlugins::ProviderLibvirt::Provider)>, []] (debian)
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: sed -e '/^#VAGRANT-BEGIN/,$ d' /etc/network/interfaces > /tmp/vagrant-network-interfaces.pre (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty

DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: sed -ne '/^#VAGRANT-END/,$ p' /etc/network/interfaces | tac | sed -e '/^#VAGRANT-END/,$ d' | tac > /tmp/vagrant-network-interfaces.post (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty

DEBUG ssh: Exit status: 0
DEBUG ssh: Uploading: /tmp/vagrant20170213-123407-11dchf6 to /tmp/vagrant-network-entry
DEBUG ssh: Re-using SSH connection.
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: cat /tmp/vagrant-network-interfaces.pre /tmp/vagrant-network-entry /tmp/vagrant-network-interfaces.post > /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 -f /tmp/vagrant-network-interfaces.pre /tmp/vagrant-network-entry /tmp/vagrant-network-interfaces.post (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty

DEBUG ssh: Exit status: 0
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworkInterfaces:0x00559c9ac215a0>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateNetworks:0x007fa84c1f1b60>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::ShareFolders:0x007fa84c2367d8>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
 INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/developer/test>] (redhat)
 INFO nfs: Using NFS, preparing NFS settings by reading host IP and machine IP
DEBUG ssh: Checking key permissions: /home/developer/test/.vagrant/machines/default/libvirt/private_key
 INFO nfs: host IP: 192.168.121.1 machine IP: 192.168.121.132
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSSettings:0x007fa84c276b30>
 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
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: default (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: ==> default: Exporting NFS shared folders...
==> default: Exporting NFS shared folders...
DEBUG host: Searching for cap: nfs_export
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_export in linux
 INFO host: Execute capability: nfs_export [#<Vagrant::Environment: /home/developer/test>, #<Vagrant::UI::Prefixed:0x00559c9b5c74b0 @logger=#<Log4r::Logger:0x00559c9b5c7460 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00559c99b41bb8 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00559c99ab54b0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00559c99ab5410>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00559c99a5fe70 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00559c99b41a78 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @prefix=:default, @ui=#<Vagrant::UI::Basic:0x00559c9a544958 @logger=#<Log4r::Logger:0x00559c9a544908 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00559c99b41bb8 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00559c99ab54b0 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00559c99ab5410>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00559c99a5fe70 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00559c99b41a78 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @lock=#<Mutex:0x00559c9a4f8490>>>, "955df75a-9a25-4de0-a775-ae8c849729af", ["192.168.121.132"], {"/vagrant"=>{:type=>:nfs, :nfs_version=>"4", :nfs_udp=>false, :guestpath=>"/vagrant", :hostpath=>"/home/developer/test", :disabled=>false, :__vagrantfile=>true, :map_uid=>1000, :map_gid=>1000, :uuid=>"1239913455"}}] (redhat)
DEBUG host: Searching for cap: nfs_apply_command
DEBUG host: Checking in: redhat
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_apply_command in linux
 INFO host: Execute capability: nfs_apply_command [#<Vagrant::Environment: /home/developer/test>] (redhat)
DEBUG host: Searching for cap: nfs_check_command
DEBUG host: Checking in: redhat
DEBUG host: Found cap: nfs_check_command in redhat
 INFO host: Execute capability: nfs_check_command [#<Vagrant::Environment: /home/developer/test>] (redhat)
DEBUG host: Searching for cap: nfs_start_command
DEBUG host: Checking in: redhat
DEBUG host: Found cap: nfs_start_command in redhat
 INFO host: Execute capability: nfs_start_command [#<Vagrant::Environment: /home/developer/test>] (redhat)
 INFO interface: info: Preparing to edit /etc/exports. Administrator privileges will be required...
 INFO interface: info: ==> default: Preparing to edit /etc/exports. Administrator privileges will be required...
==> default: Preparing to edit /etc/exports. Administrator privileges will be required...
Redirecting to /bin/systemctl status  nfs-server.service
● nfs-server.service - NFS server and services
   Loaded: loaded (/usr/lib/systemd/system/nfs-server.service; disabled; vendor preset: disabled)
   Active: active (exited) since Mon 2017-02-13 13:10:45 EET; 12min ago
  Process: 110382 ExecStart=/usr/sbin/rpc.nfsd $RPCNFSDARGS (code=exited, status=0/SUCCESS)
  Process: 110379 ExecStartPre=/usr/sbin/exportfs -r (code=exited, status=0/SUCCESS)
 Main PID: 110382 (code=exited, status=0/SUCCESS)
   CGroup: /system.slice/nfs-server.service

Feb 13 13:10:45 localhost.localdomain systemd[1]: Starting NFS server and services...
Feb 13 13:10:45 localhost.localdomain systemd[1]: Started NFS server and services.
 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: ==> default: Mounting NFS shared folders...
==> default: 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: default (VagrantPlugins::ProviderLibvirt::Provider)>, "192.168.121.1", {"/vagrant"=>{:type=>:nfs, :nfs_version=>"4", :nfs_udp=>false, :guestpath=>"/vagrant", :hostpath=>"/home/developer/test", :disabled=>false, :__vagrantfile=>true, :map_uid=>1000, :map_gid=>1000, :uuid=>"1239913455", :linux__nfs_options=>["rw", "no_subtree_check", "all_squash", "anonuid=1000", "anongid=1000", "fsid=1239913455"]}}] (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: default (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=4' 192.168.121.1:'/home/developer/test' /vagrant (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty

DEBUG ssh: Sending SSH keep-alive...
DEBUG ssh: Sending SSH keep-alive...
DEBUG ssh: Sending SSH keep-alive...
DEBUG ssh: Sending SSH keep-alive...
DEBUG ssh: Sending SSH keep-alive...
DEBUG ssh: Sending SSH keep-alive...
 INFO interface: warn: Waiting for cleanup before exiting...
 INFO interface: warn: ==> default: Waiting for cleanup before exiting...
==> default: Waiting for cleanup before exiting...
 INFO interface: error: Exiting immediately, without cleanup!
 INFO interface: error: ==> default: Exiting immediately, without cleanup!
==> default: Exiting immediately, without cleanup!
 INFO environment: Running hook: environment_unload
 INFO runner: Preparing hooks for middleware sequence...
 INFO runner: 2 hooks defined.
 INFO runner: Running action: environment_unload #<Vagrant::Action::Builder:0x00559c9b4db498>
 INFO environment: Released process lock: machine-action-6eb4838b9b208d7c9f1ea56ac8a96d2d

A Vagrantfile to reproduce the issue:

Vagrant.configure(2) do |config|
    config.vm.box = "debian/jessie64"
end

Are you using upstream vagrant package or your distros package? Distro

I can’t use the upstream version because of #706

I installed the vagrant package available in Fedora and i ran vagrant plugin update as asked by the issue.

Thanks !

About this issue

Original URL
State: closed
Created 7 years ago
Comments: 15 (6 by maintainers)

Most upvoted comments

For those using Centos 8 as a host you can run this

 firewall-cmd  --permanent --zone=libvirt --add-service=nfs
 firewall-cmd  --permanent --zone=libvirt --add-service=mountd
 firewall-cmd  --permanent --zone=libvirt --add-service=rpc-bind
 firewall-cmd  --permanent --zone=libvirt --add-port=2049/tcp
 firewall-cmd  --permanent --zone=libvirt --add-port=2049/udp
 firewall-cmd --reload

timhughes on Dec 13, 2019

Thanks for all this debugging @Wenzel !

firewall-cmd --permanent --add-service=nfs
firewall-cmd --permanent --add-service=rpc-bind
firewall-cmd --permanent --add-service=mountd
firewall-cmd --reload

Solved the problem for me on Archlinux.

little-dude on Jun 7, 2018

@Wenzel i have enabled services ipp nfs rpc-bind mountd last 3 is required for nfs

pronix on Feb 15, 2017

For those using Centos 8 as a host you can run this

 firewall-cmd  --permanent --zone=libvirt --add-service=nfs
 firewall-cmd  --permanent --zone=libvirt --add-service=mountd
 firewall-cmd  --permanent --zone=libvirt --add-service=rpc-bind
 firewall-cmd  --permanent --zone=libvirt --add-port=2049/tcp
 firewall-cmd  --permanent --zone=libvirt --add-port=2049/udp
 firewall-cmd --reload

A quick note to document that these commands are also needed on Fedora 34 so that libvirt hosted vms can use nfs mounts from the host. Thanks for documenting this!

buckaroogeek on May 6, 2021

I tried to open my firewall with the following commands:

firewall-cmd --permanent --add-service=nfs
firewall-cmd --permanent --add-service=rpc-bind
firewall-cmd --permanent --add-service=mountd
firewall-cmd --reload

I can now see nfs in the output of firewall-cmd --list-all

public (active)
  target: default
  icmp-block-inversion: no
  interfaces: eno16777736
  sources: 
  services: dhcpv6-client mdns mountd nfs rpc-bind ssh
  ports: 
  protocols: 
  masquerade: no
  forward-ports: 
  sourceports: 
  icmp-blocks: 
  rich rules:

And now the error message changed in vagrant up --debug output:

mount -o 'vers=4' 192.168.121.1:'/home/developer/tmp' /vagrant

Stdout from the command:



Stderr from the command:

stdin: is not a tty
mount.nfs: access denied by server while mounting 192.168.121.1:/home/developer/tmp

After searching a bit, the version was incorrect. If i replace vers=4 by nfsvers=3, then mount works within the guest !

However, from the vagrant documentation about NFS

nfs_version (string | integer) - The NFS protocol version to use when mounting the folder on the guest. This defaults to 3.

So why is it trying to use version 4 ?

I tried to force the version in my Vagrantfile: config.vm.synced_folder ".", "/vagrant", type: "nfs", nfs_version: 3

And this gives me a different error:

 INFO interface: info: ==> default: Mounting NFS shared folders...
==> default: 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: default (VagrantPlugins::ProviderLibvirt::Provider)>, "192.168.121.1", {"/vagrant"=>{:type=>:nfs, :nfs_version=>3, :nfs_udp=>false, :guestpath=>"/vagrant", :hostpath=>"/home/developer/tmp", :disabled=>false, :__vagrantfile=>true, :map_uid=>1000, :map_gid=>1000, :uuid=>"3496872198", :linux__nfs_options=>["rw", "no_subtree_check", "all_squash", "anonuid=1000", "anongid=1000", "fsid=3496872198"]}}] (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: default (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' 192.168.121.1:'/home/developer/tmp' /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: if command -v /sbin/init &>/dev/null && /sbin/init --version | grep upstart &>/dev/null; then
  /sbin/initctl emit --no-wait vagrant-mounted MOUNTPOINT='/vagrant'
fi
 (sudo=true)
DEBUG ssh: stderr: stdin: is not a tty

DEBUG ssh: stderr: /sbin/init: unrecognized option '--version'

DEBUG ssh: Exit status: 0
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::SyncedFolders:0x007f5a281f0ea8>
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x007f5a282381e0>
 INFO warden: Calling OUT action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x007f5a28278010>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSValidIds:0x007f5a282c45c8>
 INFO provision: Writing provisioning sentinel so we don't provision again
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Provision:0x007f5a282fee80>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateDomain:0x007f5a28352eb8>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::CreateDomainVolume:0x007f5a28399688>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::HandleBoxImage:0x007f5a283e0ce0>
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::HandleBox:0x007f5a28406cb0>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::HandleStoragePool:0x007f5a28492580>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::SetNameOfDomain:0x007f5a284b1458>
 INFO warden: Calling OUT action: #<Proc:0x00556ff4d36440@/usr/share/vagrant/lib/vagrant/action/warden.rb:94 (lambda)>
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x007f5a2801f980>
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f5a2801f9a8>
 INFO interface: Machine: action ["up", "end", {:target=>:default}]
 INFO environment: Released process lock: machine-action-d422540d9d7e272c5c6f35d215533d34
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: 2 hooks defined.
 INFO runner: Running action: environment_unload #<Vagrant::Action::Builder:0x00556ff5e70fd0>

So the NFS mount is working fine now ! But there is this command error which might be an issue though: DEBUG ssh: stderr: /sbin/init: unrecognized option '--version'

Wenzel on Feb 22, 2017