Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

fail to update the domain.memory on existing running vm 'Call to virDomainSetMemory failed' #1812

Open
rubber-ant opened this issue Mar 20, 2024 · 13 comments

Comments

@rubber-ant
Copy link

Describe the bug
after upgrade Vagrantfile fromdomain.memory=10240to domain.memory = 15240 and run sudo vagrant reload worker-1 --provision it prompt an error message. ( see below)

To Reproduce
Steps to reproduce the behavior:

  1. create a VM
wget --timestamping https://github.com/siderolabs/talos/releases/download/v1.6.6/metal-amd64.iso -O /tmp/metal-amd64.iso
# use 
Vagrant.configure("2") do |config|
 config.vm.define "worker-1" do |vm|
    vm.vm.provider :libvirt do |domain|
      domain.cpus = 8
      domain.memory = 16384
      domain.serial :type => "file", :source => {:path => "/tmp/worker-1.log"}
      #domain.storage :file, :device => :cdrom, :path => "/tmp/metal-amd64.iso"
      domain.storage :file, :size => '20G', :type => 'raw'
      domain.boot 'hd'
      #domain.boot 'cdrom'
    end
  end
end
  1. run vagrant up --provider=libvirt
  2. once the VM is running , change the memory size and run sudo vagrant reload worker-1 --provision
  3. See error
==> worker-1: Attempting graceful shutdown of VM...
    worker-1: Guest communication could not be established! This is usually because
    worker-1: SSH is not running, the authentication information was changed,
    worker-1: or some other networking issue. Vagrant will force halt, if
    worker-1: capable.
==> worker-1: Attempting direct shutdown of domain...
/root/.vagrant.d/gems/3.1.4/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/start_domain.rb:39:in `memory=': Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running (Libvirt::DefinitionError)
	from /root/.vagrant.d/gems/3.1.4/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/start_domain.rb:39:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /root/.vagrant.d/gems/3.1.4/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/set_boot_order.rb:82:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /root/.vagrant.d/gems/3.1.4/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/resolve_disk_settings.rb:143:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /root/.vagrant.d/gems/3.1.4/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/shutdown_domain.rb:15:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /root/.vagrant.d/gems/3.1.4/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/forward_ports.rb:202:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/config_validate.rb:28:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/provision.rb:83:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/config_validate.rb:28:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:247:in `action_raw'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:216:in `block in action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/environment.rb:649:in `lock'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:202:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:202:in `action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/reload/command.rb:45:in `block in execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:249:in `block in with_target_vms'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:238:in `each'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:238:in `with_target_vms'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/reload/command.rb:43:in `execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/cli.rb:67:in `execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/environment.rb:319:in `cli'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/bin/vagrant:248:in `<main>'

Expected behavior
Reload the VM with new memory size

Versions (please complete the following information)::

  • Libvirt version:
dpkg -l | grep libvirt
ii  gir1.2-libvirt-glib-1.0:amd64              4.0.0-2                                    amd64        GObject introspection files for the libvirt-glib library
ii  libsys-virt-perl                           8.0.0-1build1                              amd64        Perl module providing an extension for the libvirt library
ii  libvirt-clients                            8.0.0-1ubuntu7.8                           amd64        Programs for the libvirt library
ii  libvirt-daemon                             8.0.0-1ubuntu7.8                           amd64        Virtualization daemon
ii  libvirt-daemon-config-network              8.0.0-1ubuntu7.8                           all          Libvirt daemon configuration files (default network)
ii  libvirt-daemon-config-nwfilter             8.0.0-1ubuntu7.8                           all          Libvirt daemon configuration files (default network filters)
ii  libvirt-daemon-driver-qemu                 8.0.0-1ubuntu7.8                           amd64        Virtualization daemon QEMU connection driver
ii  libvirt-daemon-system                      8.0.0-1ubuntu7.8                           amd64        Libvirt daemon configuration files
ii  libvirt-daemon-system-systemd              8.0.0-1ubuntu7.8                           all          Libvirt daemon configuration files (systemd)
ii  libvirt-dev:amd64                          8.0.0-1ubuntu7.8                           amd64        development files for the libvirt library
ii  libvirt-glib-1.0-0:amd64                   4.0.0-2                                    amd64        libvirt GLib and GObject mapping library
ii  libvirt-glib-1.0-data                      4.0.0-2                                    all          Common files for libvirt GLib library
ii  libvirt0:amd64                             8.0.0-1ubuntu7.8                           amd64        library for interfacing with different virtualization systems
ii  python3-libvirt                            8.0.0-1build1                              amd64        libvirt Python 3 bindings
ii  ruby-fog-libvirt                           0.8.0-1                                    all          Module for the 'fog' gem to support libvirt
ii  ruby-libvirt                               0.7.1-1.1build1                            amd64        Ruby bindings for libvirt
ii  vagrant-libvirt                            0.7.0-1                                    all          Vagrant plugin that adds an Libvirt provider to Vagrant
  • Vagrant version [output of vagrant version]:
- Installed Version: 2.4.1
Latest Version: 2.4.1

  • Vagrant flavour [Upstream or Distro]:
    Using Ubuntu 22.04
  • Vagrant plugins versions (including vagrant-libvirt) [output of vagrant plugin list]:
sudo vagrant plugin list
vagrant-libvirt (0.12.2, global)

Debug Log
Attach Output of VAGRANT_LOG=debug vagrant ... --provider=libvirt >vagrant.log 2>&1

A Vagrantfile to reproduce the issue:

Vagrant.configure("2") do |config|
 config.vm.define "worker-1" do |vm|
    vm.vm.provider :libvirt do |domain|
      domain.cpus = 8
      domain.memory = 16384
      domain.serial :type => "file", :source => {:path => "/tmp/worker-1.log"}
      #domain.storage :file, :device => :cdrom, :path => "/tmp/metal-amd64.iso"
      domain.storage :file, :size => '20G', :type => 'raw'
      domain.boot 'hd'
      #domain.boot 'cdrom'
    end
  end
end
@rubber-ant
Copy link
Author

rubber-ant commented Mar 20, 2024

first attempt , it prompt errors and it shutdown the VM.

sudo vagrant reload --provision worker-1 --debug
debug logs: first_attempt_vagrant_reload_debug.log

vagrant status
worker-1              shutoff (libvirt)
sudo virsh dumpxml talos_config_worker-1 | grep -i  memory
  <memory unit='KiB'>15605760</memory>
                                        <currentMemory unit='KiB'>10485760</currentMemory>

@rubber-ant
Copy link
Author

rubber-ant commented Mar 20, 2024

On the 2nd attempt, after no changes, there was no error displayed and it start the VM
However, the VM still appears to be using the old size of memory after running the following command:

sudo vagrant reload --provision worker-1 --debug
logs: 2nd_attempt_vagrant_reload_debug.log

vagrant status
worker-1              running (libvirt)
sudo virsh dumpxml talos_config_worker-1 | grep -i  memory
  <memory unit='KiB'>15605760</memory>
                                        <currentMemory unit='KiB'>10485760</currentMemory>

serial logs from /tmp/worker-1.log
[ 0.185710] Memory: 15137216K/15605220K available (28692K kernel code, 4005K rwdata, 17656K rodata, 3156K init, 2616K bss, 467744K reserved, 0K cma-reserved)

@rubber-ant
Copy link
Author

rubber-ant commented Mar 20, 2024

I found a very dirty workaround :

  1. stop the VM
  2. sudo virsh edit talos_config_worker-1
  3. replace the currentMemory value same as the memory , example:
  <memory unit='KiB'>15605760</memory>
                                        <currentMemory unit='KiB'>15605760</currentMemory>
  1. start the VM

it shows the new memory size.

@abbbi
Copy link
Contributor

abbbi commented May 8, 2024

hi,

i cant reproduce this. Following verisons used:

$ vagrant --version
Vagrant 2.4.1
$ vagrant plugin list | grep libvirt
vagrant-libvirt (0.12.2, global)

ive tested with the following steps using a standard generic/debian12 box:

debian12 $ grep memory Vagrantfile 
      virt.memory = 2500
==> default: Machine booted and ready!
~/debian12 $ vagrant ssh -c "free -m "
               total        used        free      shared  buff/cache   available
Mem:            2407         233        2188           1         123        2174
~/debian12 $ sed -i  's/2500/3500/g' Vagrantfile
~/debian12 $ vagrant reload
==> default: Attempting graceful shutdown of VM...
[..]
==> default: Waiting for domain to get an IP address...
 [..]
==> default: Machine booted and ready.
~/debian12 $ vagrant ssh -c "free -m "
               total        used        free      shared  buff/cache   available
Mem:            3391         221        3194           1         111        3170

your logging output suggests that there is already something wrong at the point you first try to
reload, because the virtual machine is not reachable via SSH for a graceful shutdown:

==> worker-1: Attempting graceful shutdown of VM...
    worker-1: Guest communication could not be established! This is usually because
    worker-1: SSH is not running, the authentication information was changed,
    worker-1: or some other networking issue. Vagrant will force halt, if
    worker-1: capable.
==> worker-1: Attempting direct shutdown of domain...

-> the graceful shutdown is suspicious.
Is there already an up and running system at this point?

I dont think this is an bug in vagrant-libvirt, rather an special situation with the virtual machine image you
are using. What box image is used? From your vagrantfile it appears that you are booting from an iso
image and that no ssh server is reachable within the system.

What i think happens:

  1. vagrant attempts to graceful shutdown the VM during reload but fails as no ssh is reachable:

INFO graceful_halt: Graceful halt failed.

  1. vagrant then hard-kills the virtual machine:

INFO interface: info: ==> worker-1: Attempting direct shutdown of domain...

  1. And then attempts to continue with the reload action, but the reload action fails because the virtual machine was hard-killed:

ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running

According to the vagrant documentation:

https://developer.hashicorp.com/vagrant/docs/cli/reload

the "reload" action is an "halt" followed by an "up". In your case i guess "vagrant halt" is not working and as such
the code path ends up somewhere not beeing able to recover.

@electrofelix
Copy link
Contributor

There may be a bug with https://github.com/vagrant-libvirt/vagrant-libvirt/blob/main/lib/vagrant-libvirt/action/halt_domain.rb not waiting 1-2 seconds for the power off to take affect and ensure the machine is not running before continuing. Could be reaching the memory set call before the domain is powered off resulting in an attempt to change the memory being called on a running machine rather than a halted machine.

tsuchiclay added a commit to tsuchiclay/vagrant-libvirt that referenced this issue May 11, 2024
By passing a flag to libvirt's API to apply the memory size change
to the domain's configuration file, the following issue was solved.

 -  fail to update the domain.memory on existing running vm 'Call
    to virDomainSetMemory failed' vagrant-libvirt#1812
    vagrant-libvirt#1812

The problem was caused by passing only memory size to #memory= method
in start_domain.rb. Specifying a flag in addition to the memory size
causes libvirt's API, virDomainSetMemoryFlags, to be called with that
flag, explicitly choosing whether to apply the size setting to the
running domain or the config file for that domain. The missing flag was
causing the above issue by attempting to change the memory size of
a running domain that did not exist.

Therefore, a list of possible flags is introduced, and a flag that
make changes to the domain's configuration file is selected and used.

Below are links to some related documents.

 -  https://ruby.libvirt.org/api/Libvirt/Domain.html#method-i-memory-3D

 -  https://libvirt.org/html/libvirt-libvirt-domain.html#virDomainSetMemoryFlags
@tsuchiclay
Copy link

tsuchiclay commented May 11, 2024

Hi, I experienced the same things that happened to @rubber-ant, and came across this page.
I then made a pull request to address this issue, but unfortunately, as seen below, it does not pass two of the several tests that are done automatically.
https://github.com/vagrant-libvirt/vagrant-libvirt/actions/runs/9043414870?pr=1818
Sorry, I am not familiar with these tests, could you please advise me how to deal with those errors?

Below is a supplement to the pull request.

The problem seems to be solved by changing a line of lib/vagrant-libvirt/action/start_domain.rb as follows.

           if config.numa_nodes == nil
             if config.memory.to_i * 1024 != libvirt_domain.max_memory
               libvirt_domain.max_memory = config.memory.to_i * 1024
-              libvirt_domain.memory = libvirt_domain.max_memory
+              # 2 corresponds to VIR_DOMAIN_AFFECT_CONFIG of the following flags
+              # https://libvirt.org/html/libvirt-libvirt-domain.html#virDomainModificationImpact
+              libvirt_domain.memory = libvirt_domain.max_memory, 2
             end
           end

I think what was happening was the following.
The #memory= method can accept both memory size and flags as arguments.
(See https://ruby.libvirt.org/api/Libvirt/Domain.html#method-i-memory-3D)
Those flags determine whether changes are applied to the currently running domain or to the configuration file of that domain (or both).
If no flag is specified, the value of the flag seems to be treated as 0 by domain_input_to_fixnum_and_flags in #memory=.
(See https://github.com/libvirt/libvirt-ruby/blob/6af55c187014ae9e10135d45ee71c945fd2762ed/ext/libvirt/domain.c#L68)
Then, by #memory=, virDomainSetMemoryFlags is called with memory size and the flag of value 0 as arguments.
If the value of the flag is 0, virDomainSetMemoryFlags appears to make memory size changes to the running domain or to the config file for that domain based on the current state of the domain.
(See https://libvirt.org/html/libvirt-libvirt-domain.html#virDomainSetMemoryFlags https://libvirt.org/html/libvirt-libvirt-domain.html#virDomainModificationImpact)
However, for some reason, it tries to make the change to the running domain instead of the configuration file, and then the change fails with the error Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running (Libvirt::DefinitionError).

Since the domain is not actually running at the time the memory size change is made, the change only needs to be made to the configuration file.
Therefore, this problem can be avoided by specifying a flag to apply memory size changes to the configuration file.
The value of that flag is 2.

The following that happened to @rubber-ant also happened to me.
This is because on the first attempt, only max_memory was changed out of max_memory and memory, and on the second attempt, the change to memory was skipped by the if statement, if config.memory.to_i * 1024 != libvirt_domain.max_memory.

On the 2nd attempt, after no changes, there was no error displayed and it start the VM However, the VM still appears to be using the old size of memory after running the following command:

sudo vagrant reload --provision worker-1 --debug logs: 2nd_attempt_vagrant_reload_debug.log

vagrant status
worker-1              running (libvirt)
sudo virsh dumpxml talos_config_worker-1 | grep -i  memory
  <memory unit='KiB'>15605760</memory>
                                        <currentMemory unit='KiB'>10485760</currentMemory>

serial logs from /tmp/worker-1.log [ 0.185710] Memory: 15137216K/15605220K available (28692K kernel code, 4005K rwdata, 17656K rodata, 3156K init, 2616K bss, 467744K reserved, 0K cma-reserved)

@electrofelix
Copy link
Contributor

I don't believe this is the correct fix, start domain action assumes it is starting from a halted domain and I'd expect other issues to show up if other config settings are changed and it reaches this point and the domain is still running.

Suggest looking at performing a short wait after the forced halt checking for the domain to be powered off before continuing. That will ensure start calls the memory update only when the domain is stopped.

@tsuchiclay
Copy link

tsuchiclay commented May 11, 2024

Thank you for your reply!

start domain action assumes it is starting from a halted domain

Yes. Therefore, there is no running domain. Nevertheless, in start_domain.rb, virDomainSetMemoryFlags tries to set the memory size for the running domain, which is causing the problem. Therefore, it would be better to explicitly specify that memory size changes should be made for domains that are not running. In other words, change the setting to the config file.

I'd expect other issues to show up if other config settings are changed and it reaches this point and the domain is still running.

Sorry, I am not clear enough on this, what do you mean?
I do not believe that the domain is running at the time libvirt_domain.memory = libvirt_domain.max_memory is called.
When $ vagrant realod is performed, halt is actually done.

Suggest looking at performing a short wait after the forced halt checking for the domain to be powered off before continuing. That will ensure start calls the memory update only when the domain is stopped.

A $ vagrant halt followed by a $ vagrant up after a short wait will achieve that situation, but it will not solve this problem.

@electrofelix
Copy link
Contributor

Yes. Therefore, there is no running domain. Nevertheless, in start_domain.rb, virDomainSetMemoryFlags tries to set the memory size for the running domain, which is causing the problem. Therefore, it would be better to explicitly specify that memory size changes should be made for domains that are not running. In other words, change the setting to the config file.

This is assuming that the power off on the domain which hasn't taken effect before the memory change was called will be completed before the start of the domain is attempted as part of the start domain action. Essentially this focuses on the symptom of a race condition.

The failure to apply the memory change indicates that when the force halt behaviour is needed (no ssh and no acpi shutdown is working), the poweroff (pull the plug) isn't waiting for the change in state to have taken effect before continuing to the next step. In this case it is reaching the memory set before libvirt has fully powered off the machine. In other systems even with this fix, it could reach trying to update the domain xml with other config settings and then power it on before it was fully shutdown. Essentially there is a race bug here.

Sorry, I am not clear enough on this, what do you mean? I do not believe that the domain is running at the time libvirt_domain.memory = libvirt_domain.max_memory is called. When $ vagrant realod is performed, halt is actually done.

The behaviour above indicates the domain is still running at the time and with this fix it is managing to complete a poweroff at some point between when the memory is being changed and the subsequent request to power on again. This is a race and appears likely to be a cause of further issues at some point in the future.

It appears that it would be more reliable to fix the shutdown to ensure the domain is actually powered off fully. Most of the paths through the halt action that are called by reload appear to do this, however given the above log outputs, it becomes clear there is one path that is not handling this correctly.

The issue is caused because the halt domain that is called as part of the reload

b2.use Provision
b2.use action_halt
b2.use ResolveDiskSettings
b2.use action_start

Should wait for the domain to finish halting within the action_halt:

b3.use StartShutdownTimer
b3.use Call, GracefulHalt, :shutoff, :running do |env3, b4|
if !env3[:result]
b4.use Call, ShutdownDomain, :shutoff, :running do |env4, b5|
if !env4[:result]
b5.use HaltDomain
end
end
end
end

If the code goes through GracefulHalt or ShutdownDomain successfully this will be true, unfortunately if it goes through HaltDomain it is not. domain.poweroff is called and the code continues immediately:

domain = env[:machine].provider.driver.connection.servers.get(env[:machine].id.to_s)
if env[:machine].state.id == :running
env[:ui].info(I18n.t('vagrant_libvirt.halt_domain'))
domain.poweroff
end

What it should probably do is (unless env[:force_halt] is set to true) perform a short wait on the domain waiting for the state change to complete similar to how the shutdown works:

domain.shutdown
begin
domain.wait_for(timeout) { !ready? }
rescue Fog::Errors::TimeoutError
end

A $ vagrant halt followed by a $ vagrant up after a short wait will achieve that situation, but it will not solve this problem.

Sorry, I didn't mean it should be done in shell, I meant that within the plugin the halt action should be updated to wait for the halt to complete (unless the force flag is set) before continuing/returning.

should be updated to allow waiting for the power off to take effect.

@tsuchiclay
Copy link

Thanks for taking the time to explain it to me.
Now I understand what you mean.

My bad, I did not put up my logs in the first place, so here they are.
I hope it can be of very slight help about this issue.

Describe the bug
If $ vagrant reload is attempted after editing the memory size in Vagrantfile, the change is not applied correctly.

To Reproduce
Steps to reproduce the behavior:

  1. Bring up a virtual machine using the following Vagrantfile and check memory size
$ vagrant box add fedora/39-cloud-base --provider=libvirt
$ vagrant box list
fedora/39-cloud-base (libvirt, 39.20231031.1, (amd64))
# -*- mode: ruby -*-
# vi: set ft=ruby :

Vagrant.configure("2") do |config|
  config.vm.box = "fedora/39-cloud-base"
  config.vm.define "example" do |server|
    server.vm.provider :libvirt do |domain|
      domain.driver = "kvm"
      #domain.memory = 2048
    end
  end
end
$ vagrant up --provider=libvirt
$ vagrant ssh -c "free -h"
               total        used        free      shared  buff/cache   available
Mem:           443Mi       122Mi       105Mi       2.1Mi       229Mi       321Mi
Swap:          442Mi          0B       442Mi

$ sudo virsh dominfo work_example | grep mem
Max memory:     524288 KiB
Used memory:    524288 KiB
  1. Edit the memory size specification in the Vagrantfile
# -*- mode: ruby -*-
# vi: set ft=ruby :

Vagrant.configure("2") do |config|
  config.vm.box = "fedora/39-cloud-base"
  config.vm.define "example" do |server|
    server.vm.provider :libvirt do |domain|
      domain.driver = "kvm"
      domain.memory = 2048
    end
  end
end
  1. Reload to apply the change (but it is not handled correctly and will not start up)
$ vagrant status
Current machine states:

example                   running (libvirt)

The Libvirt domain is running. To stop this machine, you can run
`vagrant halt`. To destroy the machine, you can run `vagrant destroy`.

The log is pasted at the bottom of this post.

$ vagrant reload --debug &> vagrant.log
$ vagrant status
Current machine states:

example                   shutoff (libvirt)

The Libvirt domain is not running. Run `vagrant up` to start it.

Checking with virsh, the change is applied only to the maximum memory size.

$ sudo virsh dominfo work_example | grep mem
Max memory:     2097152 KiB
Used memory:    524288 KiB
  1. Try reloading again without touching anything
$ vagrant reload

This time the virtual machine starts up.

$ vagrant status
Current machine states:

example                   running (libvirt)

The Libvirt domain is running. To stop this machine, you can run
`vagrant halt`. To destroy the machine, you can run `vagrant destroy`.

However, only the size of the swap is changed.

$ vagrant ssh -c "free -h"
               total        used        free      shared  buff/cache   available
Mem:           417Mi       258Mi       156Mi       2.1Mi       144Mi       158Mi
Swap:          1.9Gi          0B       1.9Gi

Checking with virsh, only the maximum memory size is still changed.

$ sudo virsh dominfo work_example | grep mem
Max memory:     2097152 KiB
Used memory:    524288 KiB

Expected behavior
I expect the virtual machine to start up after reloading and both the memory size and the maximum memory size to change.

Versions (please complete the following information)::

  • Libvirt version:
$ libvirtd --version
libvirtd (libvirt) 10.3.0

$ virsh version --daemon
Compiled against library: libvirt 10.3.0
Using library: libvirt 10.3.0
Using API: QEMU 10.3.0
Running hypervisor: QEMU 9.0.0
Running against daemon: 10.3.0
  • Vagrant version [output of vagrant version]:
$ vagrant version
Installed Version: 2.4.1
Latest Version: 2.4.1
 
You're running an up-to-date version of Vagrant!
  • Vagrant flavour [Upstream or Distro]: Distro
$ uname -srmo
Linux 6.8.9-arch1-2 x86_64 GNU/Linux

$ pacman -Qi vagrant
Name            : vagrant
Version         : 2.4.1-1
Description     : Build and distribute virtualized development environments
Architecture    : x86_64
URL             : https://vagrantup.com
Licenses        : BUSL-1.1
Groups          : None
Provides        : None
Depends On      : curl  libarchive  libssh2  libxml2  libxslt  rsync  ruby  xz  perl
Optional Deps   : dnsmasq: if using libvirt [installed]
Required By     : None
Optional For    : None
Conflicts With  : vagrant-substrate
Replaces        : vagrant-substrate
Installed Size  : 89.33 MiB
Packager        : Jonathan Steel <jsteel@archlinux.org>
Build Date      : Sun 21 Jan 2024 08:28:34 PM JST
Install Date    : Tue 07 May 2024 11:59:29 PM JST
Install Reason  : Explicitly installed
Install Script  : No
Validated By    : Signature
  • Vagrant plugins versions (including vagrant-libvirt) [output of vagrant plugin list]:
$ vagrant plugin list
vagrant-libvirt (0.12.2, global)

Debug Log

vagrant.log

vagrant.log
 INFO global: Vagrant version: 2.4.1
 INFO global: Ruby version: 3.0.6
 INFO global: RubyGems version: 3.3.25
 INFO global: VAGRANT_DOTFILE_PATH="/home/tsuchiclay/Works/vagrant/dotfile"
 INFO global: VAGRANT_DEFAULT_PROVIDER="libvirt"
 INFO global: VAGRANT_FORCE_COLOR="1"
 INFO global: VAGRANT_HOME="/home/tsuchiclay/Works/vagrant/home"
 INFO global: VAGRANT_CWD="/home/tsuchiclay/Works/vagrant/work"
 INFO global: VAGRANT_INSTALLER_VERSION="2"
 INFO global: VAGRANT_INSTALLER_EMBEDDED_DIR="/opt/vagrant/embedded"
 INFO global: VAGRANT_INSTALLER_ENV="1"
 INFO global: VAGRANT_EXECUTABLE="/opt/vagrant/embedded/gems/gems/vagrant-2.4.1/bin/vagrant"
 INFO global: VAGRANT_LOG="debug"
 WARN global: vagrant ssl helper was not found, continuing...
 WARN global: resolv replacement has not been enabled!
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/autocomplete/plugin.rb
 INFO manager: Registered plugin: autocomplete command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/box/plugin.rb
 INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/cap/plugin.rb
 INFO manager: Registered plugin: cap command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/cloud/plugin.rb
 INFO manager: Registered plugin: vagrant-cloud
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/destroy/plugin.rb
 INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/global-status/plugin.rb
 INFO manager: Registered plugin: global-status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/halt/plugin.rb
 INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/help/plugin.rb
 INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/init/plugin.rb
 INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/list-commands/plugin.rb
 INFO manager: Registered plugin: list-commands command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/login/plugin.rb
 INFO manager: Registered plugin: vagrant-login
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/package/plugin.rb
 INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/plugin/plugin.rb
 INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/port/plugin.rb
 INFO manager: Registered plugin: port command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/powershell/plugin.rb
 INFO manager: Registered plugin: powershell command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/provider/plugin.rb
 INFO manager: Registered plugin: provider command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/provision/plugin.rb
 INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/push/plugin.rb
 INFO manager: Registered plugin: push command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/rdp/plugin.rb
 INFO manager: Registered plugin: rdp command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/reload/plugin.rb
 INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/resume/plugin.rb
 INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/serve/plugin.rb
 INFO manager: Registered plugin: start Vagrant server
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/snapshot/plugin.rb
 INFO manager: Registered plugin: snapshot command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/ssh/plugin.rb
 INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/ssh_config/plugin.rb
 INFO manager: Registered plugin: ssh-config command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/status/plugin.rb
 INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/suspend/plugin.rb
 INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/up/plugin.rb
 INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/upload/plugin.rb
 INFO manager: Registered plugin: upload command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/validate/plugin.rb
 INFO manager: Registered plugin: validate command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/version/plugin.rb
 INFO manager: Registered plugin: version command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/winrm/plugin.rb
 INFO manager: Registered plugin: winrm command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/winrm_config/plugin.rb
 INFO manager: Registered plugin: winrm-config command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/communicators/ssh/plugin.rb
 INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/communicators/winrm/plugin.rb
 INFO manager: Registered plugin: winrm communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/communicators/winssh/plugin.rb
 INFO manager: Registered plugin: windows ssh communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/alma/plugin.rb
 INFO manager: Registered plugin: Alma guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/alpine/plugin.rb
 INFO manager: Registered plugin: Alpine guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/alt/plugin.rb
 INFO manager: Registered plugin: ALT Platform guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/amazon/plugin.rb
 INFO manager: Registered plugin: Amazon Linux guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/arch/plugin.rb
 INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/atomic/plugin.rb
 INFO manager: Registered plugin: Atomic Host guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/bsd/plugin.rb
 INFO manager: Registered plugin: BSD-based guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/centos/plugin.rb
 INFO manager: Registered plugin: CentOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/coreos/plugin.rb
 INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/darwin/plugin.rb
 INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/debian/plugin.rb
 INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/dragonflybsd/plugin.rb
 INFO manager: Registered plugin: DragonFly BSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/elementary/plugin.rb
 INFO manager: Registered plugin: Elementary guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/esxi/plugin.rb
 INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/fedora/plugin.rb
 INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/freebsd/plugin.rb
 INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/funtoo/plugin.rb
 INFO manager: Registered plugin: Funtoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/gentoo/plugin.rb
 INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/haiku/plugin.rb
 INFO manager: Registered plugin: Haiku guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/kali/plugin.rb
 INFO manager: Registered plugin: Kali guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/linux/plugin.rb
 INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/mint/plugin.rb
 INFO manager: Registered plugin: Mint guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/netbsd/plugin.rb
 INFO manager: Registered plugin: NetBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/nixos/plugin.rb
 INFO manager: Registered plugin: NixOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/omnios/plugin.rb
 INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/openbsd/plugin.rb
 INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/openwrt/plugin.rb
 INFO manager: Registered plugin: OpenWrt guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/photon/plugin.rb
 INFO manager: Registered plugin: VMware Photon guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/pld/plugin.rb
 INFO manager: Registered plugin: PLD Linux guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/redhat/plugin.rb
 INFO manager: Registered plugin: Red Hat Enterprise Linux guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/rocky/plugin.rb
 INFO manager: Registered plugin: Rocky guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/slackware/plugin.rb
 INFO manager: Registered plugin: Slackware guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/smartos/plugin.rb
 INFO manager: Registered plugin: SmartOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/solaris/plugin.rb
 INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/solaris11/plugin.rb
 INFO manager: Registered plugin: Solaris 11 guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/suse/plugin.rb
 INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/tinycore/plugin.rb
 INFO manager: Registered plugin: TinyCore Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/trisquel/plugin.rb
 INFO manager: Registered plugin: Trisquel guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/ubuntu/plugin.rb
 INFO manager: Registered plugin: Ubuntu guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/windows/plugin.rb
 INFO manager: Registered plugin: Windows guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/alt/plugin.rb
 INFO manager: Registered plugin: ALT Platform host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/arch/plugin.rb
 INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/bsd/plugin.rb
 INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/darwin/plugin.rb
 INFO manager: Registered plugin: Mac OS X host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/freebsd/plugin.rb
 INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/gentoo/plugin.rb
 INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/linux/plugin.rb
 INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/null/plugin.rb
 INFO manager: Registered plugin: null host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/redhat/plugin.rb
 INFO manager: Registered plugin: Red Hat Enterprise Linux host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/slackware/plugin.rb
 INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/suse/plugin.rb
 INFO manager: Registered plugin: SUSE host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/void/plugin.rb
 INFO manager: Registered plugin: Void host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/windows/plugin.rb
 INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/kernel_v1/plugin.rb
 INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/kernel_v2/plugin.rb
 INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/docker/plugin.rb
 INFO manager: Registered plugin: docker-provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb
 INFO manager: Registered plugin: Hyper-V provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb
 INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/ansible/plugin.rb
 INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/cfengine/plugin.rb
 INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/chef/plugin.rb
 INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/container/plugin.rb
 INFO manager: Registered plugin: container
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/docker/plugin.rb
 INFO manager: Registered plugin: docker
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/file/plugin.rb
 INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/podman/plugin.rb
 INFO manager: Registered plugin: podman
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/puppet/plugin.rb
 INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/salt/plugin.rb
 INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/shell/plugin.rb
 INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/atlas/plugin.rb
 INFO manager: Registered plugin: atlas
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/ftp/plugin.rb
 INFO manager: Registered plugin: ftp
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/heroku/plugin.rb
 INFO manager: Registered plugin: heroku
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/local-exec/plugin.rb
 INFO manager: Registered plugin: local-exec
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/noop/plugin.rb
 INFO manager: Registered plugin: noop
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/synced_folders/nfs/plugin.rb
 INFO manager: Registered plugin: NFS synced folders
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/synced_folders/rsync/plugin.rb
 INFO manager: Registered plugin: RSync synced folders
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/synced_folders/smb/plugin.rb
 INFO manager: Registered plugin: SMB synced folders
 INFO vagrant: `vagrant` invoked: ["reload", "--debug"]
DEBUG vagrant: Creating Vagrant environment
 INFO environment: Environment initialized (#<Vagrant::Environment:0x00005e8aaa34b398>)
 INFO environment:   - cwd: /home/tsuchiclay/Works/vagrant/work
 INFO environment: Home path: /home/tsuchiclay/Works/vagrant/home
DEBUG environment: Effective local data path: /home/tsuchiclay/Works/vagrant/dotfile
 INFO environment: Local data path: /home/tsuchiclay/Works/vagrant/dotfile
DEBUG environment: Creating: /home/tsuchiclay/Works/vagrant/dotfile
DEBUG manager: Enabling localized plugins
 INFO manager: Plugins:
DEBUG solution_file: new solution file instance plugin_file=/home/tsuchiclay/Works/vagrant/dotfile/plugins.json solution_file=/home/tsuchiclay/Works/vagrant/dotfile/bundler/local.sol
DEBUG solution_file: missing file so skipping loading
DEBUG bundler: solution file in use for init: <Vagrant::Bundler::SolutionFile:/home/tsuchiclay/Works/vagrant/dotfile/plugins.json:/home/tsuchiclay/Works/vagrant/dotfile/bundler/local.sol:invalid>
DEBUG bundler: Generating new builtin set instance.
DEBUG bundler: Generating new plugin set instance. Skip gems - []
DEBUG bundler: generating solution set for configured plugins
DEBUG bundler: Current generated plugin dependency list: []
DEBUG bundler: resolving solution from available specification set
DEBUG bundler: solution set for configured plugins has been resolved
DEBUG bundler: activating solution set
DEBUG bundler: Activating solution set: []
DEBUG solution_file: plugin file does not exist, not storing solution
DEBUG bundler: solution set stored to - <Vagrant::Bundler::SolutionFile:/home/tsuchiclay/Works/vagrant/dotfile/plugins.json:/home/tsuchiclay/Works/vagrant/dotfile/bundler/local.sol:invalid>
 INFO manager: Loading plugins...
DEBUG manager: Enabling globalized plugins
 INFO manager: Plugins:
 INFO manager:   - vagrant-libvirt = [installed: 0.12.2 constraint: > 0]
DEBUG solution_file: new solution file instance plugin_file=/home/tsuchiclay/Works/vagrant/home/plugins.json solution_file=/home/tsuchiclay/Works/vagrant/dotfile/bundler/global.sol
DEBUG solution_file: reading solution file - /home/tsuchiclay/Works/vagrant/dotfile/bundler/global.sol
DEBUG solution_file: solution validation check CHECKSUM c2c5d79ff1b5fc0957494cbaf844df3fa4814b9da4d28d22387c4f3dd0f7f540 <-> c2c5d79ff1b5fc0957494cbaf844df3fa4814b9da4d28d22387c4f3dd0f7f540 VERSION 2.4.1 <-> 2.4.1
DEBUG solution_file: loading solution dependency list
DEBUG solution_file: solution dependency list: [#<Gem::Resolver::DependencyRequest:0x00005e8aaad16378 @dependency=<Gem::Dependency type=:runtime name="racc" requirements="~> 1.4">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaad14f28 @dependency=<Gem::Dependency type=:runtime name="nokogiri" requirements="~> 1.6">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaad14b18 @dependency=<Gem::Dependency type=:runtime name="diffy" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaad14320 @dependency=<Gem::Dependency type=:runtime name="rexml" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaad0f820 @dependency=<Gem::Dependency type=:runtime name="xml-simple" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaacefa98 @dependency=<Gem::Dependency type=:runtime name="mime-types-data" requirements="~> 3.2015">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaacef1d8 @dependency=<Gem::Dependency type=:runtime name="mime-types" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaacec258 @dependency=<Gem::Dependency type=:runtime name="formatador" requirements=">= 0.2, < 2.0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaad3b678 @dependency=<Gem::Dependency type=:runtime name="excon" requirements="~> 0.71">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aaad38220 @dependency=<Gem::Dependency type=:runtime name="builder" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aab0a7d98 @dependency=<Gem::Dependency type=:runtime name="fog-core" requirements="~> 2">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aab0a7b40 @dependency=<Gem::Dependency type=:runtime name="json" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aab0a76e0 @dependency=<Gem::Dependency type=:runtime name="ruby-libvirt" requirements=">= 0.7.0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aab0a7398 @dependency=<Gem::Dependency type=:runtime name="fog-xml" requirements="~> 0.1.1">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aab0a6df8 @dependency=<Gem::Dependency type=:runtime name="multi_json" requirements="~> 1.10">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aab0a6b00 @dependency=<Gem::Dependency type=:runtime name="fog-json" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aab0a64c0 @dependency=<Gem::Dependency type=:runtime name="fog-libvirt" requirements=">= 0.6.0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005e8aab0a5d68 @dependency=<Gem::Dependency type=:runtime name="vagrant-libvirt" requirements="= 0.12.2">, @requester=nil>]
DEBUG bundler: solution file in use for init: <Vagrant::Bundler::SolutionFile:/home/tsuchiclay/Works/vagrant/home/plugins.json:/home/tsuchiclay/Works/vagrant/dotfile/bundler/global.sol:valid>
DEBUG bundler: Generating new builtin set instance.
DEBUG bundler: Generating new plugin set instance. Skip gems - []
DEBUG bundler: loading cached solution set
DEBUG bundler: activating solution set
DEBUG bundler: Activating solution set: ["racc-1.6.0", "nokogiri-1.16.4-x86_64-linux", "diffy-3.4.2", "rexml-3.2.6", "xml-simple-1.1.9", "mime-types-data-3.2023.1205", "mime-types-3.5.2", "formatador-1.1.0", "excon-0.109.0", "builder-3.2.4", "fog-core-2.4.0", "json-2.7.1", "ruby-libvirt-0.8.2", "fog-xml-0.1.4", "multi_json-1.15.0", "fog-json-1.2.0", "fog-libvirt-0.12.0", "vagrant-libvirt-0.12.2"]
DEBUG bundler: Activating gem racc-1.6.0
DEBUG bundler: Activating gem nokogiri-1.16.4-x86_64-linux
DEBUG bundler: Activating gem diffy-3.4.2
DEBUG bundler: Activating gem xml-simple-1.1.9
DEBUG bundler: Activating gem formatador-1.1.0
DEBUG bundler: Activating gem fog-core-2.4.0
DEBUG bundler: Activating gem json-2.7.1
DEBUG bundler: Activating gem ruby-libvirt-0.8.2
DEBUG bundler: Activating gem fog-xml-0.1.4
DEBUG bundler: Activating gem fog-json-1.2.0
DEBUG bundler: Activating gem fog-libvirt-0.12.0
DEBUG bundler: Activating gem vagrant-libvirt-0.12.2
 INFO manager: Loading plugins...
 INFO manager: Loading plugin `vagrant-libvirt` with default require: `vagrant-libvirt`
 INFO manager: Registered plugin: libvirt
DEBUG manager: Successfully loaded plugin `vagrant-libvirt`.
 INFO loader: Set :root = ["#<Pathname:/home/tsuchiclay/Works/vagrant/work/Vagrantfile>"]
DEBUG loader: Populating proc cache for #<Pathname:/home/tsuchiclay/Works/vagrant/work/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/tsuchiclay/Works/vagrant/work/Vagrantfile
 INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
DEBUG environment: Default provider: `libvirt`
DEBUG environment: Using forced default provider: `libvirt`
 INFO loader: Set "860_machine_example" = ["[\"2\", #<Proc:0x00005e8aaafffeb8 /home/tsuchiclay/Works/vagrant/work/Vagrantfile:6>]"]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00005e8aaafffeb8 /home/tsuchiclay/Works/vagrant/work/Vagrantfile:6>]
 INFO loader: Loading configuration in order: [:home, :root, "860_machine_example"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO box_collection: Box found: fedora/39-cloud-base (libvirt)
 INFO environment: Running hook: authenticate_box_url
 INFO host: Autodetecting host type for [#<Vagrant::Environment: /home/tsuchiclay/Works/vagrant/work>]
DEBUG host: Trying: alt
DEBUG host: Trying: arch
 INFO host: Detected: arch!
 INFO runner: Running action: authenticate_box_url #<Vagrant::Action::Builder:0x00005e8aab34f168>
 INFO warden: Calling IN action: #<VagrantPlugins::CloudCommand::AddAuthentication:0x00005e8aab88ed68>
 WARN authenticate-box-url: Authentication token not found as GET parameter.
 INFO warden: Calling OUT action: #<VagrantPlugins::CloudCommand::AddAuthentication:0x00005e8aab88ed68>
 INFO loader: Set :"1060_fedora/39-cloud-base_libvirt" = ["#<Pathname:/home/tsuchiclay/Works/vagrant/home/boxes/fedora-VAGRANTSLASH-39-cloud-base/39.20231031.1/amd64/libvirt/Vagrantfile>"]
DEBUG loader: Populating proc cache for #<Pathname:/home/tsuchiclay/Works/vagrant/home/boxes/fedora-VAGRANTSLASH-39-cloud-base/39.20231031.1/amd64/libvirt/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/tsuchiclay/Works/vagrant/home/boxes/fedora-VAGRANTSLASH-39-cloud-base/39.20231031.1/amd64/libvirt/Vagrantfile
 INFO loader: Loading configuration in order: [:"1060_fedora/39-cloud-base_libvirt", :home, :root, "860_machine_example"]
DEBUG loader: Loading from: 1060_fedora/39-cloud-base_libvirt (evaluating)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO environment: Running hook: environment_plugins_loaded
 INFO runner: Running action: environment_plugins_loaded #<Vagrant::Action::Builder:0x00005e8aab9a9b80>
 INFO environment: Running hook: environment_load
 INFO runner: Running action: environment_load #<Vagrant::Action::Builder:0x00005e8aabb5de68>
DEBUG checkpoint_client: starting plugin check
 INFO cli: CLI: [] "reload" []
DEBUG cli: Invoking command class: VagrantPlugins::CommandReload::Command []
DEBUG checkpoint_client: waiting for checkpoint to complete...
DEBUG checkpoint_client: plugin check complete
DEBUG checkpoint_client: no information received from checkpoint
DEBUG command: 'reload' each target VM...
DEBUG command: Getting target VMs for command. Arguments:
DEBUG command:  -- names: []
DEBUG command:  -- options: nil
 INFO command: getting active machines
DEBUG command: Loading all machines...
 INFO command: Active machine found with name example. Using provider: libvirt
 INFO environment: Getting machine: example (libvirt)
 INFO environment: Uncached load of machine.
 INFO loader: Set "860_machine_example" = ["[\"2\", #<Proc:0x00005e8aaafffeb8 /home/tsuchiclay/Works/vagrant/work/Vagrantfile:6>]"]
 INFO loader: Loading configuration in order: [:home, :root, "860_machine_example"]
 WARN loader: Unknown config sources: [:"1060_fedora/39-cloud-base_libvirt"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO box_collection: Box found: fedora/39-cloud-base (libvirt)
 INFO environment: Running hook: authenticate_box_url
 INFO runner: Running action: authenticate_box_url #<Vagrant::Action::Builder:0x00005e8aaa9fe5f0>
 INFO warden: Calling IN action: #<VagrantPlugins::CloudCommand::AddAuthentication:0x00005e8aab7488f0>
 WARN authenticate-box-url: Authentication token not found as GET parameter.
 INFO warden: Calling OUT action: #<VagrantPlugins::CloudCommand::AddAuthentication:0x00005e8aab7488f0>
 INFO loader: Set :"1060_fedora/39-cloud-base_libvirt" = ["#<Pathname:/home/tsuchiclay/Works/vagrant/home/boxes/fedora-VAGRANTSLASH-39-cloud-base/39.20231031.1/amd64/libvirt/Vagrantfile>"]
 INFO loader: Loading configuration in order: [:"1060_fedora/39-cloud-base_libvirt", :home, :root, "860_machine_example"]
DEBUG loader: Loading from: 1060_fedora/39-cloud-base_libvirt (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO vmconfig: looking up provider config for: :libvirt
 INFO vmconfig: provider config value that was stored: #<VagrantPlugins::ProviderLibvirt::Config:0x00005e8aab962b18 @logger=#<Log4r::Logger:0x00005e8aab8413b0 @fullname="vagrant_libvirt::config", @outputters=[], @additive=true, @name="config", @path="vagrant_libvirt", @parent=#<Log4r::Logger:0x00005e8aab0b5150 @fullname="vagrant_libvirt", @outputters=[#<Log4r::StderrOutputter:0x00005e8aaab5ac28 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005e8aaaba09f8 @formatter=#<Log4r::BasicFormatter:0x00005e8aaaba9198 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant_libvirt", @path="", @parent=#<Log4r::RootLogger:0x00005e8aaab9be30 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @uri="qemu:///system", @driver="kvm", @host="", @port=nil, @connect_via_ssh=false, @username="root", @password=nil, @id_ssh_key_file=nil, @socket=nil, @proxy_command=nil, @forward_ssh_port=false, @storage_pool_name="default", @snapshot_pool_name="default", @random_hostname=false, @management_network_device="virbr0", @management_network_name="vagrant-libvirt", @management_network_address="192.168.121.0/24", @management_network_mode="nat", @management_network_mac=nil, @management_network_guest_ipv6="yes", @management_network_autostart=false, @management_network_pci_slot=nil, @management_network_pci_bus=nil, @management_network_domain=nil, @management_network_mtu=nil, @management_network_keep=false, @management_network_driver_iommu=false, @management_network_iface_name=nil, @management_network_model_type="virtio", @system_uri="qemu:///system", @title="", @description="", @uuid="", @memory=2048, @nodeset=nil, @memory_backing=[], @memtunes={}, @cpus=1, @cpuset=nil, @cpu_mode="host-model", @cpu_model="", @cpu_fallback="allow", @cpu_features=[], @cpu_topology={}, @cpu_affinity={}, @shares=nil, @features=["acpi", "apic", "pae"], @features_hyperv=[], @clock_absolute=nil, @clock_adjustment=nil, @clock_basis="utc", @clock_offset="utc", @clock_timezone=nil, @clock_timers=[], @launchsecurity_data=nil, @numa_nodes=nil, @loader=nil, @nvram=nil, @machine_type=nil, @machine_arch=nil, @machine_virtual_size=nil, @disk_bus="virtio", @disk_device="vda", @disk_address_type=nil, @disk_controller_model=nil, @disk_driver_opts={}, @nic_model_type="virtio", @nested=false, @volume_cache=nil, @kernel=nil, @initrd=nil, @dtb=nil, @cmd_line="", @emulator_path=nil, @graphics_type="vnc", @graphics_autoport="yes", @graphics_port=-1, @graphics_websocket=-1, @graphics_ip="127.0.0.1", @graphics_passwd=nil, @graphics_gl=false, @video_type="cirrus", @video_vram=16384, @video_accel3d=false, @sound_type=nil, @keymap="en-us", @kvm_hidden=false, @tpm_model="tpm-tis", @tpm_type="passthrough", @tpm_path=nil, @tpm_version=nil, @sysinfo={}, @memballoon_enabled=nil, @memballoon_model="virtio", @memballoon_pci_bus="0x00", @memballoon_pci_slot="0x0f", @nic_adapter_count=8, @boot_order=[], @disks=[], @cdroms=[], @floppies=[], @inputs=[{:type=>"mouse", :bus=>"ps2"}], @channels=[], @pcis=[], @rng={}, @watchdog_dev={}, @usbctl_dev={}, @usbs=[], @redirdevs=[], @redirfilters=[], @smartcard_dev={}, @suspend_mode="pause", @autostart=false, @mgmt_attach=true, @qemu_args=[], @qemu_env={}, @qemu_use_session=false, @qemu_use_agent=false, @serials=[{:type=>"pty", :source=>nil}], @host_device_exclude_prefixes=["docker", "macvtap", "virbr", "vnet"], @__invalid_methods=#<Set: {}>>
 INFO machine: Initializing machine: example
 INFO machine:   - Provider: VagrantPlugins::ProviderLibvirt::Provider
 INFO machine:   - Box: #<Vagrant::Box:0x00005e8aab89cbc0>
 INFO machine:   - Data dir: /home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt
 INFO driver: Connecting to Libvirt (qemu:///system) ...
 INFO interface: Machine: metadata ["provider", :libvirt, {:target=>:example}]
DEBUG command: have machine list to process
 INFO command: With machine: example (#<VagrantPlugins::ProviderLibvirt::Provider:0x00005e8aabb66f68 @machine=#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>, @cap_logger=#<Log4r::Logger:0x00005e8aabb66338 @fullname="vagrant::capability_host::vagrantplugins::providerlibvirt::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrantplugins::providerlibvirt", @parent=#<VagrantLogger:0x00005e8aaa87c470 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00005e8aaab5ac28 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005e8aaaba09f8 @formatter=#<Log4r::BasicFormatter:0x00005e8aaaba9198 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00005e8aaab9be30 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @cap_host_chain=[[:libvirt, #<#<Class:0x00005e8aabb66db0>:0x00005e8aaacacf18>]], @cap_args=[#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>], @cap_caps={:docker=>#<Vagrant::Registry:0x00005e8aabb66a18 @items={:public_address=>#<Proc:0x00005e8aab1e33b0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/docker/plugin.rb:63>, :proxy_machine=>#<Proc:0x00005e8aab1e32e8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/docker/plugin.rb:68>, :has_communicator=>#<Proc:0x00005e8aab1e3220 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/docker/plugin.rb:73>}, @results_cache={}>, :hyperv=>#<Vagrant::Registry:0x00005e8aabb66888 @items={:public_address=>#<Proc:0x00005e8aab1eb3a8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:28>, :snapshot_list=>#<Proc:0x00005e8aab1eb2e0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:33>, :configure_disks=>#<Proc:0x00005e8aab1eb2b8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:38>, :cleanup_disks=>#<Proc:0x00005e8aab1eb290 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:43>, :validate_disk_ext=>#<Proc:0x00005e8aab1eb268 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:48>, :default_disk_exts=>#<Proc:0x00005e8aab1eb240 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:53>, :set_default_disk_ext=>#<Proc:0x00005e8aab1eb218 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:58>}, @results_cache={}>, :virtualbox=>#<Vagrant::Registry:0x00005e8aabb667e8 @items={:forwarded_ports=>#<Proc:0x00005e8aab1f1a28 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:30>, :nic_mac_addresses=>#<Proc:0x00005e8aab1f1a00 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:35>, :public_address=>#<Proc:0x00005e8aab1f19d8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:40>, :configure_disks=>#<Proc:0x00005e8aab1f19b0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:45>, :cleanup_disks=>#<Proc:0x00005e8aab1f1988 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:50>, :validate_disk_ext=>#<Proc:0x00005e8aab1f1960 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:55>, :default_disk_exts=>#<Proc:0x00005e8aab1f1938 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:60>, :set_default_disk_ext=>#<Proc:0x00005e8aab1f1910 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:65>, :snapshot_list=>#<Proc:0x00005e8aab1f18e8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:70>}, @results_cache={}>, :libvirt=>#<Vagrant::Registry:0x00005e8aabb66658 @items={:nic_mac_addresses=>#<Proc:0x00005e8aab0b6668 /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/plugin.rb:43>, :public_address=>#<Proc:0x00005e8aab0b6528 /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/plugin.rb:48>, :snapshot_list=>#<Proc:0x00005e8aab0b64b0 /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/plugin.rb:53>}, @results_cache={}>}, @driver=#<VagrantPlugins::ProviderLibvirt::Driver:0x00005e8aabace1a0 @logger=#<Log4r::Logger:0x00005e8aabace0d8 @fullname="vagrant_libvirt::driver", @outputters=[], @additive=true, @name="driver", @path="vagrant_libvirt", @parent=#<Log4r::Logger:0x00005e8aab0b5150 @fullname="vagrant_libvirt", @outputters=[#<Log4r::StderrOutputter:0x00005e8aaab5ac28 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005e8aaaba09f8 @formatter=#<Log4r::BasicFormatter:0x00005e8aaaba9198 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant_libvirt", @path="", @parent=#<Log4r::RootLogger:0x00005e8aaab9be30 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @machine=#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>, @connection=#<Fog::Libvirt::Compute::Real:1840 @uri=#<Fog::Libvirt::Util::URI:0x00005e8aabed6388 @parsed_uri=#<URI::Generic qemu:///system>, @uri="qemu:///system"> @ip_command=" awk \"/$mac/ {print \\$1}\" /proc/net/arp " @client=#<Libvirt::Connect:0x00005e8aabed5cd0>>>>)
 INFO machine: Calling action: reload on provider Libvirt (fb8597f7-ad20-43c9-b939-fcd6ad94fc6a)
DEBUG environment: Attempting to acquire process-lock: machine-action-ea70b74c7ac898dcb2fb7122671b2f9e
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-ea70b74c7ac898dcb2fb7122671b2f9e
 INFO interface: Machine: action ["reload", "start", {:target=>:example}]
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005e8aab1bb4f0>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00005e8aabac0f28>
DEBUG syncedfolder: Searching for cap: default_fstab_modification
DEBUG syncedfolder: Checking in: rsync
 INFO config: Using vagrant-libvirt at 192.168.121.0/24 as the management network nat is the mode
DEBUG config: 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"}
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005e8aabac0de8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005e8aac01d638>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x00005e8aac0b6220>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x00005e8aac0b6220>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005e8aabf24880>
 INFO warden: Calling IN action: #<Proc:0x00005e8aac122010 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Provision:0x00005e8aabf24768>
 INFO provision: Checking provisioner sentinel file...
 INFO provision: Sentinel found! Not provisioning.
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00005e8aabf6eca0>
 INFO config: Using vagrant-libvirt at 192.168.121.0/24 as the management network nat is the mode
DEBUG config: 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"}
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ClearForwardedPorts:0x00005e8aabf6ec50>
 INFO clear_forward_ports: No ssh pids found
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005e8aabfed9d8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005e8aac1a1a90>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x00005e8aaa9ff6a8>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x00005e8aaa9ff6a8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005e8aabfec4c0>
 INFO warden: Calling IN action: #<Proc:0x00005e8aac0d6408 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005e8aabfec380>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005e8aac1a1478>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00005e8aab254920>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00005e8aab254920>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005e8aabe3fe10>
 INFO warden: Calling IN action: #<Proc:0x00005e8aabfee900 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Proc:0x00005e8aabe3fd20 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005e8aabfec308>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005e8aac0e6cb8>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00005e8aac1b3c90>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00005e8aac1b3c90>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005e8aac76f490>
 INFO warden: Calling IN action: #<Proc:0x00005e8aac804b80 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::StartShutdownTimer:0x00005e8aac76f3a0>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005e8aac76f328>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005e8aacee0008>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::GracefulHalt:0x00005e8aacfb4e48>
 INFO graceful_halt: Verifying source state of machine: :running
 INFO interface: output: Attempting graceful shutdown of VM...
 INFO interface: output: ==> example: Attempting graceful shutdown of VM...
�[1m==> example: Attempting graceful shutdown of VM...�[0m
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Checking key permissions: /home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt/private_key
 INFO ssh: Attempting SSH connection...
 INFO ssh: Attempting to connect to SSH...
 INFO ssh:   - Host: 192.168.121.224
 INFO ssh:   - Port: 22
 INFO ssh:   - Username: vagrant
 INFO ssh:   - Password? false
 INFO ssh:   - Key Path: ["/home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt/private_key"]
DEBUG ssh:   - connect_opts: {:auth_methods=>["none", "hostbased", "publickey"], :config=>false, :forward_agent=>false, :send_env=>false, :keys_only=>true, :verify_host_key=>:never, :password=>nil, :port=>22, :timeout=>15, :user_known_hosts_file=>[], :verbose=>:debug, :logger=>#<Logger:0x00005e8aaa361cb0 @level=0, @progname=nil, @default_formatter=#<Logger::Formatter:0x00005e8aaa353e80 @datetime_format=nil>, @formatter=nil, @logdev=#<Logger::LogDevice:0x00005e8aaa34ac40 @shift_period_suffix=nil, @shift_size=nil, @shift_age=nil, @filename=nil, @dev=#<StringIO:0x00005e8aaa369640>, @binmode=false, @mon_data=#<Monitor:0x00005e8aaa34a628>, @mon_data_owner_object_id=1860>>, :keys=>["/home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt/private_key"], :remote_user=>"vagrant", :keepalive=>true, :keepalive_interval=>5}
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2024-05-12T11:01:01.692761 #95830] DEBUG -- net.ssh.transport.session[758]: establishing connection to 192.168.121.224:22
D, [2024-05-12T11:01:01.693112 #95830] DEBUG -- net.ssh.transport.session[758]: connection established
I, [2024-05-12T11:01:01.693143 #95830]  INFO -- net.ssh.transport.server_version[76c]: negotiating protocol version
D, [2024-05-12T11:01:01.693150 #95830] DEBUG -- net.ssh.transport.server_version[76c]: local is `SSH-2.0-Ruby/Net::SSH_7.2.1 x86_64-linux'
D, [2024-05-12T11:01:01.705391 #95830] DEBUG -- net.ssh.transport.server_version[76c]: remote is `SSH-2.0-OpenSSH_9.3'
I, [2024-05-12T11:01:01.705484 #95830]  INFO -- net.ssh.transport.algorithms[780]: sending KEXINIT
D, [2024-05-12T11:01:01.705565 #95830] DEBUG -- socket[794]: queueing packet nr 0 type 20 len 852
D, [2024-05-12T11:01:01.705591 #95830] DEBUG -- socket[794]: sent 856 bytes
D, [2024-05-12T11:01:01.707268 #95830] DEBUG -- socket[794]: read 936 bytes
D, [2024-05-12T11:01:01.707315 #95830] DEBUG -- socket[794]: received packet nr 0 type 20 len 932
I, [2024-05-12T11:01:01.707335 #95830]  INFO -- net.ssh.transport.algorithms[780]: got KEXINIT from server
I, [2024-05-12T11:01:01.707369 #95830]  INFO -- net.ssh.transport.algorithms[780]: negotiating algorithms
D, [2024-05-12T11:01:01.707412 #95830] DEBUG -- net.ssh.transport.algorithms[780]: negotiated:
* kex: ecdh-sha2-nistp521
* host_key: ssh-ed25519
* encryption_server: aes256-ctr
* encryption_client: aes256-ctr
* hmac_client: hmac-sha2-512-etm@openssh.com
* hmac_server: hmac-sha2-512-etm@openssh.com
* compression_client: none
* compression_server: none
* language_client: 
* language_server: 
D, [2024-05-12T11:01:01.707418 #95830] DEBUG -- net.ssh.transport.algorithms[780]: exchanging keys
D, [2024-05-12T11:01:01.707719 #95830] DEBUG -- socket[794]: queueing packet nr 1 type 30 len 148
D, [2024-05-12T11:01:01.707741 #95830] DEBUG -- socket[794]: sent 152 bytes
D, [2024-05-12T11:01:01.710192 #95830] DEBUG -- socket[794]: read 312 bytes
D, [2024-05-12T11:01:01.710233 #95830] DEBUG -- socket[794]: received packet nr 1 type 31 len 292
D, [2024-05-12T11:01:01.711224 #95830] DEBUG -- socket[794]: queueing packet nr 2 type 21 len 20
D, [2024-05-12T11:01:01.711250 #95830] DEBUG -- socket[794]: sent 24 bytes
D, [2024-05-12T11:01:01.711270 #95830] DEBUG -- socket[794]: received packet nr 2 type 21 len 12
D, [2024-05-12T11:01:01.711410 #95830] DEBUG -- net.ssh.authentication.session[7a8]: beginning authentication of `vagrant'
D, [2024-05-12T11:01:01.711432 #95830] DEBUG -- socket[794]: using encrypt-then-mac
D, [2024-05-12T11:01:01.711504 #95830] DEBUG -- socket[794]: queueing packet nr 3 type 5 len 32
D, [2024-05-12T11:01:01.711513 #95830] DEBUG -- socket[794]: sent 100 bytes
D, [2024-05-12T11:01:01.752653 #95830] DEBUG -- socket[794]: read 100 bytes
D, [2024-05-12T11:01:01.752919 #95830] DEBUG -- socket[794]: received packet nr 3 type 6 len 32
D, [2024-05-12T11:01:01.753051 #95830] DEBUG -- net.ssh.authentication.session[7a8]: trying none
D, [2024-05-12T11:01:01.753174 #95830] DEBUG -- socket[794]: using encrypt-then-mac
D, [2024-05-12T11:01:01.753258 #95830] DEBUG -- socket[794]: queueing packet nr 4 type 50 len 48
D, [2024-05-12T11:01:01.753315 #95830] DEBUG -- socket[794]: sent 116 bytes
D, [2024-05-12T11:01:01.762648 #95830] DEBUG -- socket[794]: read 132 bytes
D, [2024-05-12T11:01:01.762878 #95830] DEBUG -- socket[794]: received packet nr 4 type 51 len 64
D, [2024-05-12T11:01:01.762958 #95830] DEBUG -- net.ssh.authentication.session[7a8]: allowed methods: publickey,gssapi-keyex,gssapi-with-mic,password
D, [2024-05-12T11:01:01.763017 #95830] DEBUG -- net.ssh.authentication.methods.none[7bc]: none failed
D, [2024-05-12T11:01:01.763068 #95830] DEBUG -- net.ssh.authentication.session[7a8]: trying publickey
D, [2024-05-12T11:01:01.763515 #95830] DEBUG -- net.ssh.authentication.agent[7d0]: connecting to ssh-agent
E, [2024-05-12T11:01:01.763572 #95830] ERROR -- net.ssh.authentication.agent[7d0]: could not connect to ssh-agent: Agent not configured
D, [2024-05-12T11:01:01.765552 #95830] DEBUG -- net.ssh.authentication.methods.publickey[7f8]: trying publickey (29:be:a2:9b:ee:12:cd:53:97:f6:5d:60:59:df:b2:ad) alg ssh-ed25519
D, [2024-05-12T11:01:01.765702 #95830] DEBUG -- socket[794]: using encrypt-then-mac
D, [2024-05-12T11:01:01.765810 #95830] DEBUG -- socket[794]: queueing packet nr 5 type 50 len 128
D, [2024-05-12T11:01:01.765867 #95830] DEBUG -- socket[794]: sent 196 bytes
D, [2024-05-12T11:01:01.768968 #95830] DEBUG -- socket[794]: read 148 bytes
D, [2024-05-12T11:01:01.769216 #95830] DEBUG -- socket[794]: received packet nr 5 type 60 len 80
D, [2024-05-12T11:01:01.769571 #95830] DEBUG -- socket[794]: using encrypt-then-mac
D, [2024-05-12T11:01:01.769653 #95830] DEBUG -- socket[794]: queueing packet nr 6 type 50 len 208
D, [2024-05-12T11:01:01.769712 #95830] DEBUG -- socket[794]: sent 276 bytes
D, [2024-05-12T11:01:01.797113 #95830] DEBUG -- socket[794]: read 84 bytes
D, [2024-05-12T11:01:01.797190 #95830] DEBUG -- socket[794]: received packet nr 6 type 52 len 16
D, [2024-05-12T11:01:01.797213 #95830] DEBUG -- net.ssh.authentication.methods.publickey[7f8]: publickey succeeded (29:be:a2:9b:ee:12:cd:53:97:f6:5d:60:59:df:b2:ad)

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: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 0
DEBUG ssh: Checking key permissions: /home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt/private_key
 INFO guest: Autodetecting host type for [#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>]
DEBUG guest: Trying: atomic
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: grep 'ostree=.*atomic' /proc/cmdline (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: elementary
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xelementary' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'elementary' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'elementary' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: mint
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xLinux Mint' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'Linux Mint' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'Linux Mint' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: trisquel
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: [ -x /usr/bin/lsb_release ] && /usr/bin/lsb_release -i 2>/dev/null | grep Trisquel (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: alma
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xalmalinux' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'almalinux' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'almalinux' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: alt
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: cat /etc/altlinux-release (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: stderr: cat: 
DEBUG ssh: stderr: /etc/altlinux-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr: 

DEBUG ssh: Exit status: 1
DEBUG guest: Trying: amazon
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: grep 'Amazon Linux' /etc/os-release (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: centos
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xcentos' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'centos' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'centos' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: dragonflybsd
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: uname -s | grep -i 'DragonFly' (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: fedora
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xfedora' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'fedora' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'fedora' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 0
 INFO guest: Detected: fedora!
DEBUG guest: Searching for cap: halt
DEBUG guest: Checking in: fedora
DEBUG guest: Checking in: redhat
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: halt in linux
 INFO guest: Execute capability: halt [#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>] (fedora)
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: ps -o comm= 1 | grep systemd (sudo=true)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: systemctl poweroff (sudo=true)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 0
DEBUG graceful_halt: Waiting for target graceful halt state: shutoff
 INFO graceful_halt: Gracefully halted.
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::GracefulHalt:0x00005e8aacfb4e48>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005e8aad645bb8>
 INFO warden: Calling IN action: #<Proc:0x00005e8aad8e68e0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Proc:0x00005e8aad645ac8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Proc:0x00005e8aac76f2b0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Proc:0x00005e8aabfec1f0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ResolveDiskSettings:0x00005e8aabfed938>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005e8aabf9dd70>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005e8aaba6dcb0>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00005e8aabf143e0>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00005e8aabf143e0>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005e8aac153b38>
 INFO warden: Calling IN action: #<Proc:0x00005e8aac6c2948 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005e8aac1538b8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005e8aac6faeb0>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00005e8aac75f7e8>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00005e8aac75f7e8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005e8aaaae7700>
 INFO warden: Calling IN action: #<Proc:0x00005e8aac737a68 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSValidIds:0x00005e8aaaae7408>
 INFO warden: Calling IN action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x00005e8aabe8d3e0>
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: arch
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
 INFO nfs: NFS pruning. Valid IDs: ["fb8597f7-ad20-43c9-b939-fcd6ad94fc6a"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: arch
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
 INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/tsuchiclay/Works/vagrant/work>, #<Vagrant::UI::Prefixed:0x00005e8aabb173a0 @logger=#<Log4r::Logger:0x00005e8aabb17328 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<VagrantLogger:0x00005e8aaa87c470 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00005e8aaab5ac28 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005e8aaaba09f8 @formatter=#<Log4r::BasicFormatter:0x00005e8aaaba9198 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00005e8aaab9be30 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @opts={}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @prefix=:example, @ui=#<Vagrant::UI::Colored:0x00005e8aaa332910 @logger=#<Log4r::Logger:0x00005e8aaa330c50 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<VagrantLogger:0x00005e8aaa87c470 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00005e8aaab5ac28 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005e8aaaba09f8 @formatter=#<Log4r::BasicFormatter:0x00005e8aaaba9198 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00005e8aaab9be30 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @opts={:color=>:default}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @lock=#<Thread::Mutex:0x00005e8aaac41b50>>>, ["fb8597f7-ad20-43c9-b939-fcd6ad94fc6a"]] (arch)
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x00005e8aabf0fc50>
 INFO synced_folder_cleanup: Invoking synced folder cleanup for: rsync
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Delayed:0x00005e8aabfa6c18>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolders:0x00005e8aabfa6b50>
 INFO synced_folders: SyncedFolders loading from cache: false
 INFO synced_folders: Synced Folder Implementation: rsync
 INFO synced_folders:   - /vagrant: . => /vagrant
 INFO synced_folders: Invoking synced folder prepare for: rsync
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSSettings:0x00005e8aac03ff80>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ShareFolders:0x00005e8aabfe5490>
 INFO interface: info: Creating shared folders metadata...
 INFO interface: info: ==> example: Creating shared folders metadata...
�[1m==> example: Creating shared folders metadata...�[0m
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::SetBootOrder:0x00005e8aac085fd0>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x00005e8aac1319e8>
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005e8aac1538b8>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005e8aabf9dd70>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005e8aabfed9d8>
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005e8aabfec308>
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005e8aac76f328>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005e8aabfec380>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005e8aabac0de8>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO environment: Released process lock: machine-action-ea70b74c7ac898dcb2fb7122671b2f9e
 INFO environment: Running hook: environment_unload
 INFO runner: Running action: environment_unload #<Vagrant::Action::Builder:0x00005e8aad4d6020>
/home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/start_domain.rb:39:in `memory=': Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running (Libvirt::DefinitionError)
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/start_domain.rb:39:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/set_boot_order.rb:22:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/share_folders.rb:22:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/prepare_nfs_settings.rb:21:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/synced_folders.rb:90:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/delayed.rb:22:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/synced_folder_cleanup.rb:31:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/synced_folders/nfs/action_cleanup.rb:28:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/prepare_nfs_valid_ids.rb:14:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/resolve_disk_settings.rb:143:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/shutdown_domain.rb:15:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/forward_ports.rb:202:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/config_validate.rb:28:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/provision.rb:83:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/config_validate.rb:28:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:247:in `action_raw'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:216:in `block in action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/environment.rb:649:in `lock'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:202:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:202:in `action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/reload/command.rb:45:in `block in execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:249:in `block in with_target_vms'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:238:in `each'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:238:in `with_target_vms'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/reload/command.rb:43:in `execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/cli.rb:67:in `execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/environment.rb:319:in `cli'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/bin/vagrant:248:in `<main>'

A Vagrantfile to reproduce the issue:

# -*- mode: ruby -*-
# vi: set ft=ruby :

Vagrant.configure("2") do |config|
  config.vm.box = "fedora/39-cloud-base"
  config.vm.define "example" do |server|
    server.vm.provider :libvirt do |domain|
      domain.driver = "kvm"
      domain.memory = 2048
    end
  end
end

@tsuchiclay
Copy link

Sorry to bother you, but I still don't see how this problem can be attributed to a race condition caused by a series of action_halt and action_start within a very short period of time.

In my case, the log attached to the above post appear to show that the shutdown is being done gracefully.

$ grep -i halt vagrant.log 
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/halt/plugin.rb
 INFO manager: Registered plugin: halt command
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::GracefulHalt:0x00005e8aacfb4e48>
 INFO graceful_halt: Verifying source state of machine: :running
DEBUG guest: Searching for cap: halt
DEBUG guest: Found cap: halt in linux
 INFO guest: Execute capability: halt [#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>] (fedora)
DEBUG graceful_halt: Waiting for target graceful halt state: shutoff
 INFO graceful_halt: Gracefully halted.
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::GracefulHalt:0x00005e8aacfb4e48>

If the problem is really caused by moving from action_halt to action_start too quickly, the problem should not appear if a clearly longer waiting time is inserted as shown below.

b3.use StartShutdownTimer
b3.use Call, GracefulHalt, :shutoff, :running do |env3, b4|
if !env3[:result]
b4.use Call, ShutdownDomain, :shutoff, :running do |env4, b5|
if !env4[:result]
b5.use HaltDomain
end
end
end
end

              b3.use StartShutdownTimer
              b3.use Call, GracefulHalt, :shutoff, :running do |env3, b4|
                if !env3[:result]
                  b4.use Call, ShutdownDomain, :shutoff, :running do |env4, b5|
                    if !env4[:result]
                       b5.use HaltDomain
                    end
                  end
                end
>             require "date"
>             puts "sleep start"
>             puts DateTime.now
>             sleep 300
>             puts "sleep end"
>             puts DateTime.now
              end

Doing exactly the same thing as in the above post would create the same problem at 3. Reload to apply the change (but it is not handled correctly and will not start up).
Just to be sure, the slightly longer wait also confirmed that the virtual machine was indeed shut off in vagrant and virsh, respectively.

$ vagrant reload
==> example: Attempting graceful shutdown of VM...
sleep start
2024-05-12T20:12:44+09:00
sleep end
2024-05-12T20:17:44+09:00
==> example: Creating shared folders metadata...
/home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/start_domain.rb:39:in `memory=': Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running (Libvirt::DefinitionError)
        from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/start_domain.rb:39:in `call'
*snipped*
$ date; vagrant status | grep example; sudo virsh list --all | grep example # 50 seconds after sleep has started
Sun May 12 08:13:34 PM JST 2024
example                   shutoff (libvirt)
 -    work_example   shut off

For this reason I don't see the cause on the action_halt side.

By the way, I did the exact same thing as posted above with the following Vagrantfile, using same box generic/debian12 used by @abbbi instead of fedora/39-cloud-base, and the problem still reproduces.
The reason 4096 was used instead of 2048 is simply because box uses 2048 by default.
The log for generic/debian12 is also attached just in case.

vagrant_debian.log

vagrant_debian.log
 INFO global: Vagrant version: 2.4.1
 INFO global: Ruby version: 3.0.6
 INFO global: RubyGems version: 3.3.25
 INFO global: VAGRANT_DOTFILE_PATH="/home/tsuchiclay/Works/vagrant/dotfile"
 INFO global: VAGRANT_DEFAULT_PROVIDER="libvirt"
 INFO global: VAGRANT_FORCE_COLOR="1"
 INFO global: VAGRANT_HOME="/home/tsuchiclay/Works/vagrant/home"
 INFO global: VAGRANT_CWD="/home/tsuchiclay/Works/vagrant/work"
 INFO global: VAGRANT_INSTALLER_ENV="1"
 INFO global: VAGRANT_EXECUTABLE="/opt/vagrant/embedded/gems/gems/vagrant-2.4.1/bin/vagrant"
 INFO global: VAGRANT_INSTALLER_EMBEDDED_DIR="/opt/vagrant/embedded"
 INFO global: VAGRANT_INSTALLER_VERSION="2"
 INFO global: VAGRANT_LOG="debug"
 WARN global: vagrant ssl helper was not found, continuing...
 WARN global: resolv replacement has not been enabled!
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/autocomplete/plugin.rb
 INFO manager: Registered plugin: autocomplete command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/box/plugin.rb
 INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/cap/plugin.rb
 INFO manager: Registered plugin: cap command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/cloud/plugin.rb
 INFO manager: Registered plugin: vagrant-cloud
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/destroy/plugin.rb
 INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/global-status/plugin.rb
 INFO manager: Registered plugin: global-status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/halt/plugin.rb
 INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/help/plugin.rb
 INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/init/plugin.rb
 INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/list-commands/plugin.rb
 INFO manager: Registered plugin: list-commands command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/login/plugin.rb
 INFO manager: Registered plugin: vagrant-login
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/package/plugin.rb
 INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/plugin/plugin.rb
 INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/port/plugin.rb
 INFO manager: Registered plugin: port command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/powershell/plugin.rb
 INFO manager: Registered plugin: powershell command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/provider/plugin.rb
 INFO manager: Registered plugin: provider command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/provision/plugin.rb
 INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/push/plugin.rb
 INFO manager: Registered plugin: push command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/rdp/plugin.rb
 INFO manager: Registered plugin: rdp command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/reload/plugin.rb
 INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/resume/plugin.rb
 INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/serve/plugin.rb
 INFO manager: Registered plugin: start Vagrant server
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/snapshot/plugin.rb
 INFO manager: Registered plugin: snapshot command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/ssh/plugin.rb
 INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/ssh_config/plugin.rb
 INFO manager: Registered plugin: ssh-config command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/status/plugin.rb
 INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/suspend/plugin.rb
 INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/up/plugin.rb
 INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/upload/plugin.rb
 INFO manager: Registered plugin: upload command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/validate/plugin.rb
 INFO manager: Registered plugin: validate command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/version/plugin.rb
 INFO manager: Registered plugin: version command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/winrm/plugin.rb
 INFO manager: Registered plugin: winrm command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/winrm_config/plugin.rb
 INFO manager: Registered plugin: winrm-config command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/communicators/ssh/plugin.rb
 INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/communicators/winrm/plugin.rb
 INFO manager: Registered plugin: winrm communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/communicators/winssh/plugin.rb
 INFO manager: Registered plugin: windows ssh communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/alma/plugin.rb
 INFO manager: Registered plugin: Alma guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/alpine/plugin.rb
 INFO manager: Registered plugin: Alpine guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/alt/plugin.rb
 INFO manager: Registered plugin: ALT Platform guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/amazon/plugin.rb
 INFO manager: Registered plugin: Amazon Linux guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/arch/plugin.rb
 INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/atomic/plugin.rb
 INFO manager: Registered plugin: Atomic Host guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/bsd/plugin.rb
 INFO manager: Registered plugin: BSD-based guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/centos/plugin.rb
 INFO manager: Registered plugin: CentOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/coreos/plugin.rb
 INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/darwin/plugin.rb
 INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/debian/plugin.rb
 INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/dragonflybsd/plugin.rb
 INFO manager: Registered plugin: DragonFly BSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/elementary/plugin.rb
 INFO manager: Registered plugin: Elementary guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/esxi/plugin.rb
 INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/fedora/plugin.rb
 INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/freebsd/plugin.rb
 INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/funtoo/plugin.rb
 INFO manager: Registered plugin: Funtoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/gentoo/plugin.rb
 INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/haiku/plugin.rb
 INFO manager: Registered plugin: Haiku guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/kali/plugin.rb
 INFO manager: Registered plugin: Kali guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/linux/plugin.rb
 INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/mint/plugin.rb
 INFO manager: Registered plugin: Mint guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/netbsd/plugin.rb
 INFO manager: Registered plugin: NetBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/nixos/plugin.rb
 INFO manager: Registered plugin: NixOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/omnios/plugin.rb
 INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/openbsd/plugin.rb
 INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/openwrt/plugin.rb
 INFO manager: Registered plugin: OpenWrt guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/photon/plugin.rb
 INFO manager: Registered plugin: VMware Photon guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/pld/plugin.rb
 INFO manager: Registered plugin: PLD Linux guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/redhat/plugin.rb
 INFO manager: Registered plugin: Red Hat Enterprise Linux guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/rocky/plugin.rb
 INFO manager: Registered plugin: Rocky guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/slackware/plugin.rb
 INFO manager: Registered plugin: Slackware guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/smartos/plugin.rb
 INFO manager: Registered plugin: SmartOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/solaris/plugin.rb
 INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/solaris11/plugin.rb
 INFO manager: Registered plugin: Solaris 11 guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/suse/plugin.rb
 INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/tinycore/plugin.rb
 INFO manager: Registered plugin: TinyCore Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/trisquel/plugin.rb
 INFO manager: Registered plugin: Trisquel guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/ubuntu/plugin.rb
 INFO manager: Registered plugin: Ubuntu guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/guests/windows/plugin.rb
 INFO manager: Registered plugin: Windows guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/alt/plugin.rb
 INFO manager: Registered plugin: ALT Platform host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/arch/plugin.rb
 INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/bsd/plugin.rb
 INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/darwin/plugin.rb
 INFO manager: Registered plugin: Mac OS X host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/freebsd/plugin.rb
 INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/gentoo/plugin.rb
 INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/linux/plugin.rb
 INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/null/plugin.rb
 INFO manager: Registered plugin: null host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/redhat/plugin.rb
 INFO manager: Registered plugin: Red Hat Enterprise Linux host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/slackware/plugin.rb
 INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/suse/plugin.rb
 INFO manager: Registered plugin: SUSE host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/void/plugin.rb
 INFO manager: Registered plugin: Void host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/hosts/windows/plugin.rb
 INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/kernel_v1/plugin.rb
 INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/kernel_v2/plugin.rb
 INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/docker/plugin.rb
 INFO manager: Registered plugin: docker-provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb
 INFO manager: Registered plugin: Hyper-V provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb
 INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/ansible/plugin.rb
 INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/cfengine/plugin.rb
 INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/chef/plugin.rb
 INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/container/plugin.rb
 INFO manager: Registered plugin: container
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/docker/plugin.rb
 INFO manager: Registered plugin: docker
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/file/plugin.rb
 INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/podman/plugin.rb
 INFO manager: Registered plugin: podman
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/puppet/plugin.rb
 INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/salt/plugin.rb
 INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/provisioners/shell/plugin.rb
 INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/atlas/plugin.rb
 INFO manager: Registered plugin: atlas
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/ftp/plugin.rb
 INFO manager: Registered plugin: ftp
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/heroku/plugin.rb
 INFO manager: Registered plugin: heroku
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/local-exec/plugin.rb
 INFO manager: Registered plugin: local-exec
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/pushes/noop/plugin.rb
 INFO manager: Registered plugin: noop
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/synced_folders/nfs/plugin.rb
 INFO manager: Registered plugin: NFS synced folders
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/synced_folders/rsync/plugin.rb
 INFO manager: Registered plugin: RSync synced folders
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/synced_folders/smb/plugin.rb
 INFO manager: Registered plugin: SMB synced folders
 INFO vagrant: `vagrant` invoked: ["reload", "--debug"]
DEBUG vagrant: Creating Vagrant environment
 INFO environment: Environment initialized (#<Vagrant::Environment:0x00005d4d2c3daa50>)
 INFO environment:   - cwd: /home/tsuchiclay/Works/vagrant/work
 INFO environment: Home path: /home/tsuchiclay/Works/vagrant/home
DEBUG environment: Effective local data path: /home/tsuchiclay/Works/vagrant/dotfile
 INFO environment: Local data path: /home/tsuchiclay/Works/vagrant/dotfile
DEBUG environment: Creating: /home/tsuchiclay/Works/vagrant/dotfile
DEBUG manager: Enabling localized plugins
 INFO manager: Plugins:
DEBUG solution_file: new solution file instance plugin_file=/home/tsuchiclay/Works/vagrant/dotfile/plugins.json solution_file=/home/tsuchiclay/Works/vagrant/dotfile/bundler/local.sol
DEBUG solution_file: missing file so skipping loading
DEBUG bundler: solution file in use for init: <Vagrant::Bundler::SolutionFile:/home/tsuchiclay/Works/vagrant/dotfile/plugins.json:/home/tsuchiclay/Works/vagrant/dotfile/bundler/local.sol:invalid>
DEBUG bundler: Generating new builtin set instance.
DEBUG bundler: Generating new plugin set instance. Skip gems - []
DEBUG bundler: generating solution set for configured plugins
DEBUG bundler: Current generated plugin dependency list: []
DEBUG bundler: resolving solution from available specification set
DEBUG bundler: solution set for configured plugins has been resolved
DEBUG bundler: activating solution set
DEBUG bundler: Activating solution set: []
DEBUG solution_file: plugin file does not exist, not storing solution
DEBUG bundler: solution set stored to - <Vagrant::Bundler::SolutionFile:/home/tsuchiclay/Works/vagrant/dotfile/plugins.json:/home/tsuchiclay/Works/vagrant/dotfile/bundler/local.sol:invalid>
 INFO manager: Loading plugins...
DEBUG manager: Enabling globalized plugins
 INFO manager: Plugins:
 INFO manager:   - vagrant-libvirt = [installed: 0.12.2 constraint: > 0]
DEBUG solution_file: new solution file instance plugin_file=/home/tsuchiclay/Works/vagrant/home/plugins.json solution_file=/home/tsuchiclay/Works/vagrant/dotfile/bundler/global.sol
DEBUG solution_file: reading solution file - /home/tsuchiclay/Works/vagrant/dotfile/bundler/global.sol
DEBUG solution_file: solution validation check CHECKSUM c2c5d79ff1b5fc0957494cbaf844df3fa4814b9da4d28d22387c4f3dd0f7f540 <-> c2c5d79ff1b5fc0957494cbaf844df3fa4814b9da4d28d22387c4f3dd0f7f540 VERSION 2.4.1 <-> 2.4.1
DEBUG solution_file: loading solution dependency list
DEBUG solution_file: solution dependency list: [#<Gem::Resolver::DependencyRequest:0x00005d4d2d028b68 @dependency=<Gem::Dependency type=:runtime name="racc" requirements="~> 1.4">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d0283e8 @dependency=<Gem::Dependency type=:runtime name="nokogiri" requirements="~> 1.6">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d023c58 @dependency=<Gem::Dependency type=:runtime name="diffy" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d023780 @dependency=<Gem::Dependency type=:runtime name="rexml" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d022268 @dependency=<Gem::Dependency type=:runtime name="xml-simple" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d021c78 @dependency=<Gem::Dependency type=:runtime name="mime-types-data" requirements="~> 3.2015">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d021110 @dependency=<Gem::Dependency type=:runtime name="mime-types" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2cff2fb8 @dependency=<Gem::Dependency type=:runtime name="formatador" requirements=">= 0.2, < 2.0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2cff1820 @dependency=<Gem::Dependency type=:runtime name="excon" requirements="~> 0.71">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2cff0ce0 @dependency=<Gem::Dependency type=:runtime name="builder" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2cff0150 @dependency=<Gem::Dependency type=:runtime name="fog-core" requirements="~> 2">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d387d68 @dependency=<Gem::Dependency type=:runtime name="json" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d387930 @dependency=<Gem::Dependency type=:runtime name="ruby-libvirt" requirements=">= 0.7.0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d387480 @dependency=<Gem::Dependency type=:runtime name="fog-xml" requirements="~> 0.1.1">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d387098 @dependency=<Gem::Dependency type=:runtime name="multi_json" requirements="~> 1.10">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d386b48 @dependency=<Gem::Dependency type=:runtime name="fog-json" requirements=">= 0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d386238 @dependency=<Gem::Dependency type=:runtime name="fog-libvirt" requirements=">= 0.6.0">, @requester=nil>, #<Gem::Resolver::DependencyRequest:0x00005d4d2d385cc0 @dependency=<Gem::Dependency type=:runtime name="vagrant-libvirt" requirements="= 0.12.2">, @requester=nil>]
DEBUG bundler: solution file in use for init: <Vagrant::Bundler::SolutionFile:/home/tsuchiclay/Works/vagrant/home/plugins.json:/home/tsuchiclay/Works/vagrant/dotfile/bundler/global.sol:valid>
DEBUG bundler: Generating new builtin set instance.
DEBUG bundler: Generating new plugin set instance. Skip gems - []
DEBUG bundler: loading cached solution set
DEBUG bundler: activating solution set
DEBUG bundler: Activating solution set: ["racc-1.6.0", "nokogiri-1.16.4-x86_64-linux", "diffy-3.4.2", "rexml-3.2.6", "xml-simple-1.1.9", "mime-types-data-3.2023.1205", "mime-types-3.5.2", "formatador-1.1.0", "excon-0.109.0", "builder-3.2.4", "fog-core-2.4.0", "json-2.7.1", "ruby-libvirt-0.8.2", "fog-xml-0.1.4", "multi_json-1.15.0", "fog-json-1.2.0", "fog-libvirt-0.12.0", "vagrant-libvirt-0.12.2"]
DEBUG bundler: Activating gem racc-1.6.0
DEBUG bundler: Activating gem nokogiri-1.16.4-x86_64-linux
DEBUG bundler: Activating gem diffy-3.4.2
DEBUG bundler: Activating gem xml-simple-1.1.9
DEBUG bundler: Activating gem formatador-1.1.0
DEBUG bundler: Activating gem fog-core-2.4.0
DEBUG bundler: Activating gem json-2.7.1
DEBUG bundler: Activating gem ruby-libvirt-0.8.2
DEBUG bundler: Activating gem fog-xml-0.1.4
DEBUG bundler: Activating gem fog-json-1.2.0
DEBUG bundler: Activating gem fog-libvirt-0.12.0
DEBUG bundler: Activating gem vagrant-libvirt-0.12.2
 INFO manager: Loading plugins...
 INFO manager: Loading plugin `vagrant-libvirt` with default require: `vagrant-libvirt`
 INFO manager: Registered plugin: libvirt
DEBUG manager: Successfully loaded plugin `vagrant-libvirt`.
 INFO loader: Set :root = ["#<Pathname:/home/tsuchiclay/Works/vagrant/work/Vagrantfile>"]
DEBUG loader: Populating proc cache for #<Pathname:/home/tsuchiclay/Works/vagrant/work/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/tsuchiclay/Works/vagrant/work/Vagrantfile
 INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
DEBUG environment: Default provider: `libvirt`
DEBUG environment: Using forced default provider: `libvirt`
 INFO loader: Set "860_machine_example" = ["[\"2\", #<Proc:0x00005d4d2d320348 /home/tsuchiclay/Works/vagrant/work/Vagrantfile:6>]"]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00005d4d2d320348 /home/tsuchiclay/Works/vagrant/work/Vagrantfile:6>]
 INFO loader: Loading configuration in order: [:home, :root, "860_machine_example"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO box_collection: Box found: generic/debian12 (libvirt)
 INFO environment: Running hook: authenticate_box_url
 INFO host: Autodetecting host type for [#<Vagrant::Environment: /home/tsuchiclay/Works/vagrant/work>]
DEBUG host: Trying: alt
DEBUG host: Trying: arch
 INFO host: Detected: arch!
 INFO runner: Running action: authenticate_box_url #<Vagrant::Action::Builder:0x00005d4d2d626e48>
 INFO warden: Calling IN action: #<VagrantPlugins::CloudCommand::AddAuthentication:0x00005d4d2db66cc8>
 WARN authenticate-box-url: Authentication token not found as GET parameter.
 INFO warden: Calling OUT action: #<VagrantPlugins::CloudCommand::AddAuthentication:0x00005d4d2db66cc8>
 INFO loader: Set :"1060_generic/debian12_libvirt" = ["#<Pathname:/home/tsuchiclay/Works/vagrant/home/boxes/generic-VAGRANTSLASH-debian12/4.3.12/amd64/libvirt/Vagrantfile>"]
DEBUG loader: Populating proc cache for #<Pathname:/home/tsuchiclay/Works/vagrant/home/boxes/generic-VAGRANTSLASH-debian12/4.3.12/amd64/libvirt/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/tsuchiclay/Works/vagrant/home/boxes/generic-VAGRANTSLASH-debian12/4.3.12/amd64/libvirt/Vagrantfile
 INFO loader: Loading configuration in order: [:"1060_generic/debian12_libvirt", :home, :root, "860_machine_example"]
DEBUG loader: Loading from: 1060_generic/debian12_libvirt (evaluating)
DEBUG loader: Loading from: 1060_generic/debian12_libvirt (evaluating)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO vmconfig: config class lookup for provider :hyperv gave us base class: VagrantPlugins::HyperV::Config
 INFO vmconfig: config class lookup for provider :parallels gave us base class: Vagrant::Config::V2::DummyConfig
 INFO vmconfig: config class lookup for provider :virtualbox gave us base class: VagrantPlugins::ProviderVirtualBox::Config
 INFO vmconfig: config class lookup for provider :vmware_fusion gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: config class lookup for provider :vmware_workstation gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: config class lookup for provider :vmware_desktop gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO loader: Set :"860_vm_example_generic/debian12_libvirt" = ["[\"2\", #<Proc:0x00005d4d2d4cf3d8>]"]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00005d4d2d4cf3d8>]
 INFO loader: Loading configuration in order: [:"1060_generic/debian12_libvirt", :home, :root, "860_machine_example", :"860_vm_example_generic/debian12_libvirt"]
DEBUG loader: Loading from: 1060_generic/debian12_libvirt (cache)
DEBUG loader: Loading from: 1060_generic/debian12_libvirt (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (cache)
DEBUG loader: Loading from: 860_vm_example_generic/debian12_libvirt (evaluating)
DEBUG dummy_config: found name disk_bus=
DEBUG dummy_config: setting instance variable name disk_bus
DEBUG dummy_config: found name driver=
DEBUG dummy_config: setting instance variable name driver
DEBUG dummy_config: found name video_vram=
DEBUG dummy_config: setting instance variable name video_vram
DEBUG dummy_config: found name memory=
DEBUG dummy_config: setting instance variable name memory
DEBUG dummy_config: found name cpus=
DEBUG dummy_config: setting instance variable name cpus
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO vmconfig: config class lookup for provider :hyperv gave us base class: VagrantPlugins::HyperV::Config
 INFO vmconfig: config class lookup for provider :parallels gave us base class: Vagrant::Config::V2::DummyConfig
 INFO vmconfig: config class lookup for provider :virtualbox gave us base class: VagrantPlugins::ProviderVirtualBox::Config
 INFO vmconfig: config class lookup for provider :vmware_fusion gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: config class lookup for provider :vmware_workstation gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: config class lookup for provider :vmware_desktop gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO environment: Running hook: environment_plugins_loaded
 INFO runner: Running action: environment_plugins_loaded #<Vagrant::Action::Builder:0x00005d4d2d440d18>
 INFO environment: Running hook: environment_load
 INFO runner: Running action: environment_load #<Vagrant::Action::Builder:0x00005d4d2da638a8>
DEBUG checkpoint_client: starting plugin check
 INFO cli: CLI: [] "reload" []
DEBUG checkpoint_client: plugin check complete
DEBUG cli: Invoking command class: VagrantPlugins::CommandReload::Command []
DEBUG checkpoint_client: no information received from checkpoint
DEBUG command: 'reload' each target VM...
DEBUG command: Getting target VMs for command. Arguments:
DEBUG command:  -- names: []
DEBUG command:  -- options: nil
 INFO command: getting active machines
DEBUG command: Loading all machines...
 INFO command: Active machine found with name example. Using provider: libvirt
 INFO environment: Getting machine: example (libvirt)
 INFO environment: Uncached load of machine.
 INFO loader: Set "860_machine_example" = ["[\"2\", #<Proc:0x00005d4d2d320348 /home/tsuchiclay/Works/vagrant/work/Vagrantfile:6>]"]
 INFO loader: Loading configuration in order: [:home, :root, "860_machine_example"]
 WARN loader: Unknown config sources: [:"1060_generic/debian12_libvirt", :"860_vm_example_generic/debian12_libvirt"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO box_collection: Box found: generic/debian12 (libvirt)
 INFO environment: Running hook: authenticate_box_url
 INFO runner: Running action: authenticate_box_url #<Vagrant::Action::Builder:0x00005d4d2de08b20>
 INFO warden: Calling IN action: #<VagrantPlugins::CloudCommand::AddAuthentication:0x00005d4d2d35fe80>
 WARN authenticate-box-url: Authentication token not found as GET parameter.
 INFO warden: Calling OUT action: #<VagrantPlugins::CloudCommand::AddAuthentication:0x00005d4d2d35fe80>
 INFO loader: Set :"1060_generic/debian12_libvirt" = ["#<Pathname:/home/tsuchiclay/Works/vagrant/home/boxes/generic-VAGRANTSLASH-debian12/4.3.12/amd64/libvirt/Vagrantfile>"]
 INFO loader: Loading configuration in order: [:"1060_generic/debian12_libvirt", :home, :root, "860_machine_example"]
 WARN loader: Unknown config sources: [:"860_vm_example_generic/debian12_libvirt"]
DEBUG loader: Loading from: 1060_generic/debian12_libvirt (cache)
DEBUG loader: Loading from: 1060_generic/debian12_libvirt (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO vmconfig: config class lookup for provider :hyperv gave us base class: VagrantPlugins::HyperV::Config
 INFO vmconfig: config class lookup for provider :parallels gave us base class: Vagrant::Config::V2::DummyConfig
 INFO vmconfig: config class lookup for provider :virtualbox gave us base class: VagrantPlugins::ProviderVirtualBox::Config
 INFO vmconfig: config class lookup for provider :vmware_fusion gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: config class lookup for provider :vmware_workstation gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: config class lookup for provider :vmware_desktop gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO loader: Set :"860_vm_example_generic/debian12_libvirt" = ["[\"2\", #<Proc:0x00005d4d2d4cf3d8>]"]
 INFO loader: Loading configuration in order: [:"1060_generic/debian12_libvirt", :home, :root, "860_machine_example", :"860_vm_example_generic/debian12_libvirt"]
DEBUG loader: Loading from: 1060_generic/debian12_libvirt (cache)
DEBUG loader: Loading from: 1060_generic/debian12_libvirt (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 860_machine_example (cache)
DEBUG loader: Loading from: 860_vm_example_generic/debian12_libvirt (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
 INFO vmconfig: config class lookup for provider :libvirt gave us base class: VagrantPlugins::ProviderLibvirt::Config
 INFO vmconfig: config class lookup for provider :hyperv gave us base class: VagrantPlugins::HyperV::Config
 INFO vmconfig: config class lookup for provider :parallels gave us base class: Vagrant::Config::V2::DummyConfig
 INFO vmconfig: config class lookup for provider :virtualbox gave us base class: VagrantPlugins::ProviderVirtualBox::Config
 INFO vmconfig: config class lookup for provider :vmware_fusion gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: config class lookup for provider :vmware_workstation gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: config class lookup for provider :vmware_desktop gave us base class: Vagrant::Config::V2::DummyConfig
DEBUG dummy_config: found name whitelist_verified=
DEBUG dummy_config: setting instance variable name whitelist_verified
DEBUG dummy_config: found name gui=
DEBUG dummy_config: setting instance variable name gui
 INFO vmconfig: looking up provider config for: :libvirt
 INFO vmconfig: provider config value that was stored: #<VagrantPlugins::ProviderLibvirt::Config:0x00005d4d2dcfacb0 @logger=#<Log4r::Logger:0x00005d4d2dc75588 @fullname="vagrant_libvirt::config", @outputters=[], @additive=true, @name="config", @path="vagrant_libvirt", @parent=#<Log4r::Logger:0x00005d4d2d38ca98 @fullname="vagrant_libvirt", @outputters=[#<Log4r::StderrOutputter:0x00005d4d2ce509d0 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005d4d2ce7bf18 @formatter=#<Log4r::BasicFormatter:0x00005d4d2ce995e0 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant_libvirt", @path="", @parent=#<Log4r::RootLogger:0x00005d4d2cb512e8 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @uri="qemu:///system", @driver="kvm", @host="", @port=nil, @connect_via_ssh=false, @username=nil, @password=nil, @id_ssh_key_file=nil, @socket=nil, @proxy_command=nil, @forward_ssh_port=false, @storage_pool_name="default", @snapshot_pool_name="default", @random_hostname=false, @management_network_device="virbr0", @management_network_name="vagrant-libvirt", @management_network_address="192.168.121.0/24", @management_network_mode="nat", @management_network_mac=nil, @management_network_guest_ipv6="yes", @management_network_autostart=false, @management_network_pci_slot=nil, @management_network_pci_bus=nil, @management_network_domain=nil, @management_network_mtu=nil, @management_network_keep=false, @management_network_driver_iommu=false, @management_network_iface_name=nil, @management_network_model_type="virtio", @system_uri="qemu:///system", @title="", @description="", @uuid="", @memory=4096, @nodeset=nil, @memory_backing=[], @memtunes={}, @cpus=2, @cpuset=nil, @cpu_mode="host-model", @cpu_model="", @cpu_fallback="allow", @cpu_features=[], @cpu_topology={}, @cpu_affinity={}, @shares=nil, @features=["acpi", "apic", "pae"], @features_hyperv=[], @clock_absolute=nil, @clock_adjustment=nil, @clock_basis="utc", @clock_offset="utc", @clock_timezone=nil, @clock_timers=[], @launchsecurity_data=nil, @numa_nodes=nil, @loader=nil, @nvram=nil, @machine_type=nil, @machine_arch=nil, @machine_virtual_size=nil, @disk_bus="virtio", @disk_device="vda", @disk_address_type=nil, @disk_controller_model=nil, @disk_driver_opts={}, @nic_model_type="virtio", @nested=false, @volume_cache=nil, @kernel=nil, @initrd=nil, @dtb=nil, @cmd_line="", @emulator_path=nil, @graphics_type="vnc", @graphics_autoport="yes", @graphics_port=-1, @graphics_websocket=-1, @graphics_ip="127.0.0.1", @graphics_passwd=nil, @graphics_gl=false, @video_type="cirrus", @video_vram=256, @video_accel3d=false, @sound_type=nil, @keymap="en-us", @kvm_hidden=false, @tpm_model="tpm-tis", @tpm_type="passthrough", @tpm_path=nil, @tpm_version=nil, @sysinfo={}, @memballoon_enabled=nil, @memballoon_model="virtio", @memballoon_pci_bus="0x00", @memballoon_pci_slot="0x0f", @nic_adapter_count=8, @boot_order=[], @disks=[], @cdroms=[], @floppies=[], @inputs=[{:type=>"mouse", :bus=>"ps2"}], @channels=[], @pcis=[], @rng={}, @watchdog_dev={}, @usbctl_dev={}, @usbs=[], @redirdevs=[], @redirfilters=[], @smartcard_dev={}, @suspend_mode="pause", @autostart=false, @mgmt_attach=true, @qemu_args=[], @qemu_env={}, @qemu_use_session=false, @qemu_use_agent=false, @serials=[{:type=>"pty", :source=>nil}], @host_device_exclude_prefixes=["docker", "macvtap", "virbr", "vnet"], @__invalid_methods=#<Set: {}>>
 INFO machine: Initializing machine: example
 INFO machine:   - Provider: VagrantPlugins::ProviderLibvirt::Provider
 INFO machine:   - Box: #<Vagrant::Box:0x00005d4d2d54baa0>
 INFO machine:   - Data dir: /home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt
 INFO driver: Connecting to Libvirt (qemu:///system) ...
 INFO interface: Machine: metadata ["provider", :libvirt, {:target=>:example}]
DEBUG command: have machine list to process
 INFO command: With machine: example (#<VagrantPlugins::ProviderLibvirt::Provider:0x00005d4d2dc677a8 @machine=#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>, @cap_logger=#<Log4r::Logger:0x00005d4d2dc66ce0 @fullname="vagrant::capability_host::vagrantplugins::providerlibvirt::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrantplugins::providerlibvirt", @parent=#<VagrantLogger:0x00005d4d2cb51798 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00005d4d2ce509d0 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005d4d2ce7bf18 @formatter=#<Log4r::BasicFormatter:0x00005d4d2ce995e0 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00005d4d2cb512e8 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @cap_host_chain=[[:libvirt, #<#<Class:0x00005d4d2dc67640>:0x00005d4d2dcf3a28>]], @cap_args=[#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>], @cap_caps={:docker=>#<Vagrant::Registry:0x00005d4d2dc67370 @items={:public_address=>#<Proc:0x00005d4d2d4ac270 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/docker/plugin.rb:63>, :proxy_machine=>#<Proc:0x00005d4d2d4ac1a8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/docker/plugin.rb:68>, :has_communicator=>#<Proc:0x00005d4d2d4ac0e0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/docker/plugin.rb:73>}, @results_cache={}>, :hyperv=>#<Vagrant::Registry:0x00005d4d2dc67258 @items={:public_address=>#<Proc:0x00005d4d2d4b8250 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:28>, :snapshot_list=>#<Proc:0x00005d4d2d4b8188 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:33>, :configure_disks=>#<Proc:0x00005d4d2d4b8160 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:38>, :cleanup_disks=>#<Proc:0x00005d4d2d4b8138 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:43>, :validate_disk_ext=>#<Proc:0x00005d4d2d4b8110 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:48>, :default_disk_exts=>#<Proc:0x00005d4d2d4b80e8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:53>, :set_default_disk_ext=>#<Proc:0x00005d4d2d4b80c0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/hyperv/plugin.rb:58>}, @results_cache={}>, :virtualbox=>#<Vagrant::Registry:0x00005d4d2dc67168 @items={:forwarded_ports=>#<Proc:0x00005d4d2d4ce898 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:30>, :nic_mac_addresses=>#<Proc:0x00005d4d2d4ce870 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:35>, :public_address=>#<Proc:0x00005d4d2d4ce848 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:40>, :configure_disks=>#<Proc:0x00005d4d2d4ce820 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:45>, :cleanup_disks=>#<Proc:0x00005d4d2d4ce7f8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:50>, :validate_disk_ext=>#<Proc:0x00005d4d2d4ce7d0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:55>, :default_disk_exts=>#<Proc:0x00005d4d2d4ce7a8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:60>, :set_default_disk_ext=>#<Proc:0x00005d4d2d4ce780 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:65>, :snapshot_list=>#<Proc:0x00005d4d2d4ce758 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/providers/virtualbox/plugin.rb:70>}, @results_cache={}>, :libvirt=>#<Vagrant::Registry:0x00005d4d2dc670a0 @items={:nic_mac_addresses=>#<Proc:0x00005d4d2d38d380 /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/plugin.rb:43>, :public_address=>#<Proc:0x00005d4d2d38d358 /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/plugin.rb:48>, :snapshot_list=>#<Proc:0x00005d4d2d38d330 /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/plugin.rb:53>}, @results_cache={}>}, @driver=#<VagrantPlugins::ProviderLibvirt::Driver:0x00005d4d2da85278 @logger=#<Log4r::Logger:0x00005d4d2da85160 @fullname="vagrant_libvirt::driver", @outputters=[], @additive=true, @name="driver", @path="vagrant_libvirt", @parent=#<Log4r::Logger:0x00005d4d2d38ca98 @fullname="vagrant_libvirt", @outputters=[#<Log4r::StderrOutputter:0x00005d4d2ce509d0 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005d4d2ce7bf18 @formatter=#<Log4r::BasicFormatter:0x00005d4d2ce995e0 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant_libvirt", @path="", @parent=#<Log4r::RootLogger:0x00005d4d2cb512e8 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @machine=#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>, @connection=#<Fog::Libvirt::Compute::Real:2280 @uri=#<Fog::Libvirt::Util::URI:0x00005d4d2e1cd878 @parsed_uri=#<URI::Generic qemu:///system>, @uri="qemu:///system"> @ip_command=" awk \"/$mac/ {print \\$1}\" /proc/net/arp " @client=#<Libvirt::Connect:0x00005d4d2e1cd148>>>>)
 INFO machine: Calling action: reload on provider Libvirt (7deaaca9-4387-4c3a-9e88-f1992d6387f7)
DEBUG environment: Attempting to acquire process-lock: machine-action-ea70b74c7ac898dcb2fb7122671b2f9e
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-ea70b74c7ac898dcb2fb7122671b2f9e
 INFO interface: Machine: action ["reload", "start", {:target=>:example}]
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005d4d2e5063f0>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00005d4d2d6f6f30>
 INFO config: Using vagrant-libvirt at 192.168.121.0/24 as the management network nat is the mode
DEBUG config: 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"}
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005d4d2d6f6cd8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005d4d2dc6f610>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x00005d4d2e166ee8>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x00005d4d2e166ee8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005d4d2e50d588>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2e19ea00 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Provision:0x00005d4d2e50d470>
 INFO provision: Checking provisioner sentinel file...
 INFO provision: Sentinel found! Not provisioning.
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00005d4d2d451280>
 INFO config: Using vagrant-libvirt at 192.168.121.0/24 as the management network nat is the mode
DEBUG config: 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"}
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ClearForwardedPorts:0x00005d4d2d4504e8>
 INFO clear_forward_ports: No ssh pids found
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005d4d2c5da6e8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005d4d2e245080>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x00005d4d2e2d5fb8>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsCreated:0x00005d4d2e2d5fb8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005d4d2e4325f0>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2da84468 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005d4d2e4324b0>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005d4d2daef790>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00005d4d2e1e56a8>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00005d4d2e1e56a8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005d4d2e33d5f0>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2e4e53d0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2e33d460 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005d4d2e432410>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005d4d2e5047d0>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00005d4d2dac96d0>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00005d4d2dac96d0>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005d4d2e3459f8>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2e50d9e8 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::StartShutdownTimer:0x00005d4d2e3457a0>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005d4d2e345638>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005d4d2e53cec8>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::GracefulHalt:0x00005d4d2ea18eb0>
 INFO graceful_halt: Verifying source state of machine: :running
 INFO interface: output: Attempting graceful shutdown of VM...
 INFO interface: output: ==> example: Attempting graceful shutdown of VM...
�[1m==> example: Attempting graceful shutdown of VM...�[0m
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Checking key permissions: /home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt/private_key
 INFO ssh: Attempting SSH connection...
 INFO ssh: Attempting to connect to SSH...
 INFO ssh:   - Host: 192.168.121.100
 INFO ssh:   - Port: 22
 INFO ssh:   - Username: vagrant
 INFO ssh:   - Password? false
 INFO ssh:   - Key Path: ["/home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt/private_key"]
DEBUG ssh:   - connect_opts: {:auth_methods=>["none", "hostbased", "publickey"], :config=>false, :forward_agent=>false, :send_env=>false, :keys_only=>true, :verify_host_key=>:never, :password=>nil, :port=>22, :timeout=>15, :user_known_hosts_file=>[], :verbose=>:debug, :logger=>#<Logger:0x00005d4d2f72f950 @level=0, @progname=nil, @default_formatter=#<Logger::Formatter:0x00005d4d2f72f860 @datetime_format=nil>, @formatter=nil, @logdev=#<Logger::LogDevice:0x00005d4d2f72f6f8 @shift_period_suffix=nil, @shift_size=nil, @shift_age=nil, @filename=nil, @dev=#<StringIO:0x00005d4d2f72fa18>, @binmode=false, @mon_data=#<Monitor:0x00005d4d2f72f630>, @mon_data_owner_object_id=2300>>, :keys=>["/home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt/private_key"], :remote_user=>"vagrant", :keepalive=>true, :keepalive_interval=>5}
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2024-05-12T15:12:47.456541 #3423] DEBUG -- net.ssh.transport.session[910]: establishing connection to 192.168.121.100:22
D, [2024-05-12T15:12:47.456808 #3423] DEBUG -- net.ssh.transport.session[910]: connection established
I, [2024-05-12T15:12:47.456841 #3423]  INFO -- net.ssh.transport.server_version[924]: negotiating protocol version
D, [2024-05-12T15:12:47.456848 #3423] DEBUG -- net.ssh.transport.server_version[924]: local is `SSH-2.0-Ruby/Net::SSH_7.2.1 x86_64-linux'
D, [2024-05-12T15:12:47.462910 #3423] DEBUG -- net.ssh.transport.server_version[924]: remote is `SSH-2.0-OpenSSH_9.2p1 Debian-2+deb12u2'
I, [2024-05-12T15:12:47.462999 #3423]  INFO -- net.ssh.transport.algorithms[938]: sending KEXINIT
D, [2024-05-12T15:12:47.463089 #3423] DEBUG -- socket[94c]: queueing packet nr 0 type 20 len 852
D, [2024-05-12T15:12:47.463113 #3423] DEBUG -- socket[94c]: sent 856 bytes
D, [2024-05-12T15:12:47.463510 #3423] DEBUG -- socket[94c]: read 1112 bytes
D, [2024-05-12T15:12:47.463540 #3423] DEBUG -- socket[94c]: received packet nr 0 type 20 len 1108
I, [2024-05-12T15:12:47.463555 #3423]  INFO -- net.ssh.transport.algorithms[938]: got KEXINIT from server
I, [2024-05-12T15:12:47.463591 #3423]  INFO -- net.ssh.transport.algorithms[938]: negotiating algorithms
D, [2024-05-12T15:12:47.463642 #3423] DEBUG -- net.ssh.transport.algorithms[938]: negotiated:
* kex: ecdh-sha2-nistp521
* host_key: ssh-ed25519
* encryption_server: aes256-ctr
* encryption_client: aes256-ctr
* hmac_client: hmac-sha2-512-etm@openssh.com
* hmac_server: hmac-sha2-512-etm@openssh.com
* compression_client: none
* compression_server: none
* language_client: 
* language_server: 
D, [2024-05-12T15:12:47.463648 #3423] DEBUG -- net.ssh.transport.algorithms[938]: exchanging keys
D, [2024-05-12T15:12:47.463941 #3423] DEBUG -- socket[94c]: queueing packet nr 1 type 30 len 148
D, [2024-05-12T15:12:47.463960 #3423] DEBUG -- socket[94c]: sent 152 bytes
D, [2024-05-12T15:12:47.465388 #3423] DEBUG -- socket[94c]: read 312 bytes
D, [2024-05-12T15:12:47.465409 #3423] DEBUG -- socket[94c]: received packet nr 1 type 31 len 292
D, [2024-05-12T15:12:47.466408 #3423] DEBUG -- socket[94c]: queueing packet nr 2 type 21 len 20
D, [2024-05-12T15:12:47.466431 #3423] DEBUG -- socket[94c]: sent 24 bytes
D, [2024-05-12T15:12:47.466456 #3423] DEBUG -- socket[94c]: received packet nr 2 type 21 len 12
D, [2024-05-12T15:12:47.466601 #3423] DEBUG -- net.ssh.authentication.session[960]: beginning authentication of `vagrant'
D, [2024-05-12T15:12:47.466623 #3423] DEBUG -- socket[94c]: using encrypt-then-mac
D, [2024-05-12T15:12:47.466689 #3423] DEBUG -- socket[94c]: queueing packet nr 3 type 5 len 32
D, [2024-05-12T15:12:47.466697 #3423] DEBUG -- socket[94c]: sent 100 bytes
D, [2024-05-12T15:12:47.510235 #3423] DEBUG -- socket[94c]: read 100 bytes
D, [2024-05-12T15:12:47.510591 #3423] DEBUG -- socket[94c]: received packet nr 3 type 6 len 32
D, [2024-05-12T15:12:47.510747 #3423] DEBUG -- net.ssh.authentication.session[960]: trying none
D, [2024-05-12T15:12:47.510911 #3423] DEBUG -- socket[94c]: using encrypt-then-mac
D, [2024-05-12T15:12:47.511005 #3423] DEBUG -- socket[94c]: queueing packet nr 4 type 50 len 48
D, [2024-05-12T15:12:47.511072 #3423] DEBUG -- socket[94c]: sent 116 bytes
D, [2024-05-12T15:12:47.517793 #3423] DEBUG -- socket[94c]: read 100 bytes
D, [2024-05-12T15:12:47.518058 #3423] DEBUG -- socket[94c]: received packet nr 4 type 51 len 32
D, [2024-05-12T15:12:47.518146 #3423] DEBUG -- net.ssh.authentication.session[960]: allowed methods: publickey,password
D, [2024-05-12T15:12:47.518218 #3423] DEBUG -- net.ssh.authentication.methods.none[974]: none failed
D, [2024-05-12T15:12:47.518276 #3423] DEBUG -- net.ssh.authentication.session[960]: trying publickey
D, [2024-05-12T15:12:47.518798 #3423] DEBUG -- net.ssh.authentication.agent[988]: connecting to ssh-agent
E, [2024-05-12T15:12:47.518862 #3423] ERROR -- net.ssh.authentication.agent[988]: could not connect to ssh-agent: Agent not configured
D, [2024-05-12T15:12:47.519018 #3423] DEBUG -- net.ssh.authentication.methods.publickey[9b0]: trying publickey (9b:91:d7:ba:04:44:b1:b0:35:18:e7:3b:1d:0d:ca:c1) alg ssh-ed25519
D, [2024-05-12T15:12:47.519119 #3423] DEBUG -- socket[94c]: using encrypt-then-mac
D, [2024-05-12T15:12:47.519247 #3423] DEBUG -- socket[94c]: queueing packet nr 5 type 50 len 128
D, [2024-05-12T15:12:47.519305 #3423] DEBUG -- socket[94c]: sent 196 bytes
D, [2024-05-12T15:12:47.521153 #3423] DEBUG -- socket[94c]: read 148 bytes
D, [2024-05-12T15:12:47.521393 #3423] DEBUG -- socket[94c]: received packet nr 5 type 60 len 80
D, [2024-05-12T15:12:47.521714 #3423] DEBUG -- socket[94c]: using encrypt-then-mac
D, [2024-05-12T15:12:47.521823 #3423] DEBUG -- socket[94c]: queueing packet nr 6 type 50 len 208
D, [2024-05-12T15:12:47.521882 #3423] DEBUG -- socket[94c]: sent 276 bytes
D, [2024-05-12T15:12:47.532805 #3423] DEBUG -- socket[94c]: read 84 bytes
D, [2024-05-12T15:12:47.533181 #3423] DEBUG -- socket[94c]: received packet nr 6 type 52 len 16
D, [2024-05-12T15:12:47.533272 #3423] DEBUG -- net.ssh.authentication.methods.publickey[9b0]: publickey succeeded (9b:91:d7:ba:04:44:b1:b0:35:18:e7:3b:1d:0d:ca:c1)

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: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 0
DEBUG ssh: Checking key permissions: /home/tsuchiclay/Works/vagrant/dotfile/machines/example/libvirt/private_key
 INFO guest: Autodetecting host type for [#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>]
DEBUG guest: Trying: atomic
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: grep 'ostree=.*atomic' /proc/cmdline (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: elementary
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xelementary' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'elementary' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'elementary' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: mint
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xLinux Mint' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'Linux Mint' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'Linux Mint' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: trisquel
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: [ -x /usr/bin/lsb_release ] && /usr/bin/lsb_release -i 2>/dev/null | grep Trisquel (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: alma
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xalmalinux' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'almalinux' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'almalinux' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: alt
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: cat /etc/altlinux-release (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: stderr: cat: 
DEBUG ssh: stderr: /etc/altlinux-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr: 

DEBUG ssh: Exit status: 1
DEBUG guest: Trying: amazon
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: grep 'Amazon Linux' /etc/os-release (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: centos
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xcentos' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'centos' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'centos' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: dragonflybsd
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: uname -s | grep -i 'DragonFly' (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: fedora
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xfedora' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'fedora' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'fedora' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
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: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
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: kali
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xkali' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'kali' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'kali' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
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: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
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: rocky
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xrocky' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'rocky' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'rocky' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: ubuntu
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xubuntu' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'ubuntu' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'ubuntu' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: alpine
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: cat /etc/alpine-release (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: stderr: cat: 
DEBUG ssh: stderr: /etc/alpine-release
DEBUG ssh: stderr: : No such file or directory
DEBUG ssh: stderr: 

DEBUG ssh: Exit status: 1
DEBUG guest: Trying: arch
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: cat /etc/arch-release (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
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: coreos
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: (cat /etc/os-release | grep ID=coreos) || (cat /etc/os-release | grep -E 'ID_LIKE=.*coreos.*') (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: darwin
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: uname -s | grep 'Darwin' (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 1
DEBUG guest: Trying: debian
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: if test -r /etc/os-release; then
source /etc/os-release && test 'xdebian' = "x$ID" && exit
fi
if test -x /usr/bin/lsb_release; then
/usr/bin/lsb_release -i 2>/dev/null | grep -qi 'debian' && exit
fi
if test -r /etc/issue; then
cat /etc/issue | grep -qi 'debian' && exit
fi
exit 1
 (sudo=false)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 0
 INFO guest: Detected: debian!
DEBUG guest: Searching for cap: halt
DEBUG guest: Checking in: debian
DEBUG guest: Checking in: linux
DEBUG guest: Found cap: halt in linux
 INFO guest: Execute capability: halt [#<Vagrant::Machine: example (VagrantPlugins::ProviderLibvirt::Provider)>] (debian)
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: ps -o comm= 1 | grep systemd (sudo=true)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 0
DEBUG ssh: Re-using SSH connection.
 INFO ssh: Execute: systemctl poweroff (sudo=true)
DEBUG ssh: stderr: 41e57d38-b4f7-4e46-9c38-13873d338b86-vagrant-ssh
DEBUG ssh: Exit status: 0
DEBUG graceful_halt: Waiting for target graceful halt state: shutoff
 INFO graceful_halt: Gracefully halted.
 INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::GracefulHalt:0x00005d4d2ea18eb0>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005d4d2e54f320>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2e9cb9d0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2e54f050 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2e345520 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2e432370 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ResolveDiskSettings:0x00005d4d2c5d8898>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005d4d2d577128>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005d4d2ed69e70>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00005d4d2f76ab40>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsRunning:0x00005d4d2f76ab40>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005d4d2ed0db48>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2fd4b0a0 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00005d4d2ed0daa8>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Builder:0x00005d4d2f4565d0>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00005d4d2f30cc38>
 INFO warden: Calling OUT action: #<VagrantPlugins::ProviderLibvirt::Action::IsSuspended:0x00005d4d2f30cc38>
 INFO runner: Running action: machine_action_reload #<Vagrant::Action::Warden:0x00005d4d2ec678b0>
 INFO warden: Calling IN action: #<Proc:0x00005d4d2fd0fa00 /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:116 (lambda)>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSValidIds:0x00005d4d2ec67770>
 INFO warden: Calling IN action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x00005d4d2ed839b0>
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: arch
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
 INFO nfs: NFS pruning. Valid IDs: ["7deaaca9-4387-4c3a-9e88-f1992d6387f7"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: arch
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
 INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/tsuchiclay/Works/vagrant/work>, #<Vagrant::UI::Prefixed:0x00005d4d2db047d0 @logger=#<Log4r::Logger:0x00005d4d2db04758 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<VagrantLogger:0x00005d4d2cb51798 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00005d4d2ce509d0 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005d4d2ce7bf18 @formatter=#<Log4r::BasicFormatter:0x00005d4d2ce995e0 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00005d4d2cb512e8 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @opts={}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @prefix=:example, @ui=#<Vagrant::UI::Colored:0x00005d4d2c3d87a0 @logger=#<Log4r::Logger:0x00005d4d2c3d8598 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<VagrantLogger:0x00005d4d2cb51798 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00005d4d2ce509d0 @name="stderr", @level=0, @formatter=#<Vagrant::Util::LoggingFormatter:0x00005d4d2ce7bf18 @formatter=#<Log4r::BasicFormatter:0x00005d4d2ce995e0 @depth=7>>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00005d4d2cb512e8 @level=2, @outputters=[]>, @level=2, @trace=false>, @level=2, @trace=false>, @opts={:color=>:default}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @lock=#<Thread::Mutex:0x00005d4d2cf49508>>>, ["7deaaca9-4387-4c3a-9e88-f1992d6387f7"]] (arch)
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x00005d4d2ec8fbd0>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Delayed:0x00005d4d2f32dd48>
 INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolders:0x00005d4d2f32dca8>
 INFO synced_folders: SyncedFolders loading from cache: false
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::PrepareNFSSettings:0x00005d4d2f6e6bd8>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::ShareFolders:0x00005d4d2f7796b8>
 INFO interface: info: Creating shared folders metadata...
 INFO interface: info: ==> example: Creating shared folders metadata...
�[1m==> example: Creating shared folders metadata...�[0m
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::SetBootOrder:0x00005d4d2f73c768>
 INFO warden: Calling IN action: #<VagrantPlugins::ProviderLibvirt::Action::StartDomain:0x00005d4d2f798b58>
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005d4d2ed0daa8>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005d4d2d577128>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005d4d2c5da6e8>
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005d4d2e432410>
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005d4d2e345638>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005d4d2e4324b0>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x00005d4d2d6f6cd8>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 INFO environment: Released process lock: machine-action-ea70b74c7ac898dcb2fb7122671b2f9e
 INFO environment: Running hook: environment_unload
 INFO runner: Running action: environment_unload #<Vagrant::Action::Builder:0x00005d4d2f3405b0>
/home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/start_domain.rb:39:in `memory=': Call to virDomainSetMemory failed: Requested operation is not valid: domain is not running (Libvirt::DefinitionError)
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/start_domain.rb:39:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/set_boot_order.rb:22:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/share_folders.rb:22:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/prepare_nfs_settings.rb:21:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/synced_folders.rb:90:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/delayed.rb:22:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/synced_folder_cleanup.rb:31:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/synced_folders/nfs/action_cleanup.rb:28:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/prepare_nfs_valid_ids.rb:14:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/resolve_disk_settings.rb:143:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/shutdown_domain.rb:15:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /home/tsuchiclay/Works/vagrant/home/gems/3.0.6/gems/vagrant-libvirt-0.12.2/lib/vagrant-libvirt/action/forward_ports.rb:202:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/config_validate.rb:28:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/provision.rb:83:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:117:in `block in finalize_action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/call.rb:56:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builtin/config_validate.rb:28:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/warden.rb:38:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/builder.rb:183:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `block in run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/util/busy.rb:22:in `busy'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/action/runner.rb:104:in `run'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:247:in `action_raw'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:216:in `block in action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/environment.rb:649:in `lock'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:202:in `call'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/machine.rb:202:in `action'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/reload/command.rb:45:in `block in execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:249:in `block in with_target_vms'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:238:in `each'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/plugin/v2/command.rb:238:in `with_target_vms'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/plugins/commands/reload/command.rb:43:in `execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/cli.rb:67:in `execute'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/lib/vagrant/environment.rb:319:in `cli'
	from /opt/vagrant/embedded/gems/gems/vagrant-2.4.1/bin/vagrant:248:in `<main>'

# -*- mode: ruby -*-
# vi: set ft=ruby :

Vagrant.configure("2") do |config|
  config.vm.box = "generic/debian12"
  config.vm.define "example" do |server|
    server.vm.provider :libvirt do |domain|
      domain.driver = "kvm"
      domain.memory = 4096
    end
  end
end

@electrofelix
Copy link
Contributor

Thanks, my original assumption was if it worked in one case and not in another it had to be to do with what was different between the two runs, the need for a power off rather than graceful shutdown.

I need to go look at how this ever worked though and see if there is anything else that needs to be looked at (e.g. did libvirt behaviour change over time)

@electrofelix
Copy link
Contributor

It's entirely possible this never worked and the previous outter begin/rescue that I removed was previously masking that it was failing here.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

4 participants