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.

Sign up for GitHub

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

Jump to bottom

Pool error in create_volume #108

Closed
sciurus opened this issue Dec 8, 2013 · 10 comments
Closed

Pool error in create_volume #108

sciurus opened this issue Dec 8, 2013 · 10 comments
Assignees
@miurahr
Labels
bug
Milestone
0.1.5

Comments

@sciurus
Copy link
Contributor

sciurus commented Dec 8, 2013

I installed vagrant-kvm from trunk at commit ca68836

When I try to bring up a VM, I get this (abbreviated) error.

INFO driver: Creating storage pool vagrant in /home/pittsb/.vagrant.d/tmp/storage-pool
INFO box_collection: Searching for box: debian-wheezy (kvm) in /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/metadata.json
INFO box_collection: Box found: debian-wheezy (kvm)
INFO interface: info: Importing base box 'debian-wheezy'...
INFO driver: Creating storage pool vagrant_1000_debian-wheezy in /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm
INFO driver: Creating volume box-disk1-1386542158.img backed by volume /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/box-disk1.img
ERROR warden: Error occurred: undefined method `create_volume_xml' for nil:NilClass

The storage pool does exist,

$ virsh --connect qemu:///system pool-list
Name                 State      Autostart 
-----------------------------------------
default              active     yes       
vagrant              active     no        
vagrant_1000_debian-wheezy active     no

so perhaps the @pool variable isn't being set correctly?

Here is the full error.

 INFO global: Vagrant version: 1.3.1
 INFO manager: Registered plugin: Fedora host
 INFO manager: Registered plugin: OpenSUSE host
 INFO manager: Registered plugin: Arch host
 INFO manager: Registered plugin: FreeBSD host
 INFO manager: Registered plugin: Slackware host
 INFO manager: Registered plugin: Linux host
 INFO manager: Registered plugin: BSD host
 INFO manager: Registered plugin: Windows host
 INFO manager: Registered plugin: Gentoo host
 INFO manager: Registered plugin: kernel
 INFO manager: Registered plugin: shell
 INFO manager: Registered plugin: CFEngine Provisioner
 INFO manager: Registered plugin: salt
 INFO manager: Registered plugin: ansible
 INFO manager: Registered plugin: file
 INFO manager: Registered plugin: puppet
 INFO manager: Registered plugin: chef
 INFO manager: Registered plugin: kernel
 INFO manager: Registered plugin: VirtualBox provider
 INFO manager: Registered plugin: box command
 INFO manager: Registered plugin: plugin command
 INFO manager: Registered plugin: ssh command
 INFO manager: Registered plugin: resume command
 INFO manager: Registered plugin: destroy command
 INFO manager: Registered plugin: init command
 INFO manager: Registered plugin: provision command
 INFO manager: Registered plugin: package command
 INFO manager: Registered plugin: ssh-config command
 INFO manager: Registered plugin: help command
 INFO manager: Registered plugin: halt command
 INFO manager: Registered plugin: status command
 INFO manager: Registered plugin: reload command
 INFO manager: Registered plugin: suspend command
 INFO manager: Registered plugin: up command
 INFO manager: Registered plugin: ssh communicator
 INFO manager: Registered plugin: Fedora guest
 INFO manager: Registered plugin: Solaris 11 guest.
 INFO manager: Registered plugin: Arch guest
 INFO manager: Registered plugin: OpenBSD guest
 INFO manager: Registered plugin: FreeBSD guest
 INFO manager: Registered plugin: SUSE guest
 INFO manager: Registered plugin: Darwin guest
 INFO manager: Registered plugin: Linux guest.
 INFO manager: Registered plugin: Debian guest
 INFO manager: Registered plugin: CoreOS guest
 INFO manager: Registered plugin: Gentoo guest
 INFO manager: Registered plugin: Solaris guest.
 INFO manager: Registered plugin: PLD Linux guest
 INFO manager: Registered plugin: OmniOS guest.
 INFO manager: Registered plugin: RedHat guest
 INFO manager: Registered plugin: Ubuntu guest
 INFO vagrant: `vagrant` invoked: ["up", "--provider=kvm"]
 INFO environment: Environment initialized (#<Vagrant::Environment:0x000000010ee6f0>)
 INFO environment:   - cwd: /home/pittsb/vagrant/kvm-test
 INFO environment: Home path: /home/pittsb/.vagrant.d
 INFO environment: Creating: /home/pittsb/.vagrant.d/tmp
 INFO environment: Local data path: /home/pittsb/vagrant/kvm-test/.vagrant
 INFO environment: Loading plugin from JSON: sahara
 INFO manager: Registered plugin: sahara
 INFO environment: Loading plugin from JSON: vagrant-kvm
 INFO manager: Registered plugin: KVM provider
 INFO environment: Loading plugin from JSON: vagrant-mutate
 INFO manager: Registered plugin: vagrant-mutate
 INFO environment: Running hook: environment_load
 INFO environment: Initializing config...
 INFO loader: Set :default = "/opt/vagrant/embedded/gems/gems/vagrant-1.3.1/config/default.rb"
 INFO loader: Set :root = #<Pathname:/home/pittsb/vagrant/kvm-test/Vagrantfile>
 INFO loader: Loading configuration in order: [:default, :home, :root]
 INFO hosts: Host class: VagrantPlugins::HostLinux::Host
 INFO runner: Running action: #<Vagrant::Action::Builder:0x00000001c27670>
 INFO cli: CLI: [] "up" ["--provider=kvm"]
 INFO environment: Getting machine: test_vm (kvm)
 INFO environment: Uncached load of machine.
 INFO loader: Set :vm_test_vm = [["2", #<Proc:0x00000001b4b030@/home/pittsb/vagrant/kvm-test/Vagrantfile:9>]]
 INFO loader: Loading configuration in order: [:default, :home, :root, :vm_test_vm]
 INFO box_collection: Searching for box: debian-wheezy (kvm) in /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/metadata.json
 INFO box_collection: Box found: debian-wheezy (kvm)
 INFO environment: Box exists with Vagrantfile. Reloading machine config.
 INFO loader: Set :"box_debian-wheezy_kvm" = #<Pathname:/home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/Vagrantfile>
 INFO loader: Loading configuration in order: [:default, :"box_debian-wheezy_kvm", :home, :root, :vm_test_vm]
 INFO machine: Initializing machine: test_vm
 INFO machine:   - Provider: VagrantPlugins::ProviderKvm::Provider
 INFO machine:   - Box: #<Vagrant::Box:0x00000001b52b28>
 INFO machine:   - Data dir: /home/pittsb/vagrant/kvm-test/.vagrant/machines/test_vm/kvm
 INFO driver: Check KVM kernel modules
 INFO command: With machine: test_vm (QEMU/KVM (new VM))
 INFO interface: info: Bringing machine 'test_vm' up with 'kvm' provider...
 INFO batch_action: Enabling parallelization by default.
 INFO batch_action: Disabling parallelization because provider doesn't support it: kvm
 INFO batch_action: Batch action will parallelize: false
 INFO batch_action: Starting action: #<Vagrant::Machine:0x000000015b67c8> up {:destroy_on_error=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil, :provider=>"kvm"}
 INFO machine: Calling action: up on provider QEMU/KVM (new VM)
 INFO runner: Running action: #<Vagrant::Action::Builder:0x00000001d71468>
 INFO warden: Calling action: #<VagrantPlugins::ProviderKvm::Action::CheckKvm:0x000000012e14d0>
 INFO driver: Check KVM kernel modules
 INFO warden: Calling action: #<VagrantPlugins::ProviderKvm::Action::SetName:0x000000012e1390>
 INFO setname: Setting the name of the VM: kvm-test_1386542158
 INFO warden: Calling action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000001691710>
 INFO warden: Calling action: #<VagrantPlugins::ProviderKvm::Action::InitStoragePool:0x000000016916e8>
 INFO driver: Check KVM kernel modules
 INFO driver: Creating storage pool vagrant in /home/pittsb/.vagrant.d/tmp/storage-pool
 INFO warden: Calling action: #<Vagrant::Action::Builtin::Call:0x000000016915f8>
 INFO runner: Running action: #<Vagrant::Action::Builder:0x00000001d7abd0>
 INFO warden: Calling action: #<VagrantPlugins::ProviderKvm::Action::Created:0x00000001d87a88>
 INFO runner: Running action: #<Vagrant::Action::Warden:0x00000001f05310>
 INFO warden: Calling action: #<VagrantPlugins::ProviderKvm::Action::CheckBox:0x00000001f05298>
 INFO box_collection: Searching for box: debian-wheezy (kvm) in /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/metadata.json
 INFO box_collection: Box found: debian-wheezy (kvm)
 INFO warden: Calling action: #<VagrantPlugins::ProviderKvm::Action::Import:0x00000001f05248>
 INFO interface: info: Importing base box 'debian-wheezy'...
 INFO driver: Creating storage pool vagrant_1000_debian-wheezy in /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm
 INFO driver: Creating volume box-disk1-1386542158.img backed by volume /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/box-disk1.img
ERROR warden: Error occurred: undefined method `create_volume_xml' for nil:NilClass
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<VagrantPlugins::ProviderKvm::Action::Import:0x00000001f05248>
 INFO warden: Recovery complete.
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
ERROR warden: Error occurred: undefined method `create_volume_xml' for nil:NilClass
 INFO warden: Beginning recovery process...
 INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x000000016915f8>
 INFO warden: Beginning recovery process...
 INFO warden: Recovery complete.
 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 environment: Running hook: environment_unload
 INFO runner: Running action: #<Vagrant::Action::Builder:0x007f2d40841330>
/home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/driver/driver.rb:123:in `create_volume': undefined method `create_volume_xml' for nil:NilClass (NoMethodError)
        from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/import.rb:89:in `import_volume'
        from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/import.rb:39:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/warden.rb:34:in `call'
        from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/check_box.rb:31:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/warden.rb:34:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/runner.rb:61:in `block in run'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/util/busy.rb:19:in `busy'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/runner.rb:61:in `run'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/builtin/call.rb:51:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/warden.rb:34:in `call'
        from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/init_storage_pool.rb:14:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/warden.rb:34:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/builtin/config_validate.rb:25:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/warden.rb:34:in `call'
        from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/set_name.rb:25:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/warden.rb:34:in `call'
        from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/check_kvm.rb:18:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/warden.rb:34:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/builder.rb:116:in `call'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/runner.rb:61:in `block in run'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/util/busy.rb:19:in `busy'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/action/runner.rb:61:in `run'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/machine.rb:147:in `action'
        from /opt/vagrant/embedded/gems/gems/vagrant-1.3.1/lib/vagrant/batch_action.rb:63:in `block (2 levels) in run'
@adrahon
Copy link
Owner

adrahon commented Dec 9, 2013

There seem to be a missing error message from qemu here, can you try with VAGRANT_LOG=debug? It looks like the regression I mentioned here #102 (comment)

Try reverting the change to lines 224-229 and see if it works.

@miurahr
Copy link
Collaborator

miurahr commented Dec 11, 2013

In log messages, we can observe;

INFO driver: Creating storage pool vagrant in /home/pittsb/.vagrant.d/tmp/storage-pool

that is in init_storage(base_path) and return pool to following function in driver.c.

    # Initialize or create storage pool
    def init_storage(base_path)
      # Storage pool doesn't exist so we create it
      # create dir if it doesn't exist
      # if we let libvirt create the dir it is owned by root
      pool_path = base_path.join("storage-pool")
      pool_path.mkpath unless Dir.exists?(pool_path)
      @pool = init_storage_directory(pool_path, @pool_name)
    end

@pool should be set by this sequence, but become nil. why?

@sciurus
Copy link
Contributor Author

sciurus commented Dec 11, 2013

Errors from debug log level are below

INFO global: Vagrant version: 1.3.5
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/opensuse/plugin.rb
INFO manager: Registered plugin: OpenSUSE host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/arch/plugin.rb
INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/slackware/plugin.rb
INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/linux/plugin.rb
INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/bsd/plugin.rb
INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/windows/plugin.rb
INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/hosts/redhat/plugin.rb
INFO manager: Registered plugin: Red Hat host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/kernel_v1/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/provisioners/shell/plugin.rb
INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/provisioners/cfengine/plugin.rb
INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/provisioners/salt/plugin.rb
INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/provisioners/ansible/plugin.rb
INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/provisioners/file/plugin.rb
INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/provisioners/puppet/plugin.rb
INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/provisioners/chef/plugin.rb
INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/kernel_v2/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/providers/virtualbox/plugin.rb
INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/box/plugin.rb
INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/plugin/plugin.rb
INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/ssh/plugin.rb
INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/resume/plugin.rb
INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/destroy/plugin.rb
INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/init/plugin.rb
INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/provision/plugin.rb
INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/package/plugin.rb
INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/ssh_config/plugin.rb
INFO manager: Registered plugin: ssh-config command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/help/plugin.rb
INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/halt/plugin.rb
INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/status/plugin.rb
INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/reload/plugin.rb
INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/suspend/plugin.rb
INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/commands/up/plugin.rb
INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/communicators/ssh/plugin.rb
INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/fedora/plugin.rb
INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/solaris11/plugin.rb
INFO manager: Registered plugin: Solaris 11 guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/arch/plugin.rb
INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/openbsd/plugin.rb
INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/suse/plugin.rb
INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/darwin/plugin.rb
INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/esxi/plugin.rb
INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/linux/plugin.rb
INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/debian/plugin.rb
INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/coreos/plugin.rb
INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/solaris/plugin.rb
INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/pld/plugin.rb
INFO manager: Registered plugin: PLD Linux guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/omnios/plugin.rb
INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/redhat/plugin.rb
INFO manager: Registered plugin: RedHat guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/plugins/guests/ubuntu/plugin.rb
INFO manager: Registered plugin: Ubuntu guest
INFO vagrant: vagrant invoked: ["up", "--provider", "kvm"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#Vagrant::Environment:0x00000000a8ff20)
INFO environment: - cwd: /home/pittsb/vagrant/kvm-test
INFO environment: Home path: /home/pittsb/.vagrant.d
INFO environment: Creating: /home/pittsb/.vagrant.d/tmp
INFO environment: Local data path: /home/pittsb/vagrant/kvm-test/.vagrant
DEBUG environment: Creating: /home/pittsb/vagrant/kvm-test/.vagrant
DEBUG environment: Loading plugins from: /home/pittsb/.vagrant.d/plugins.json
INFO environment: Loading plugin from JSON: sahara
INFO manager: Registered plugin: sahara
INFO environment: Loading plugin from JSON: vagrant-kvm
INFO manager: Registered plugin: KVM provider
INFO environment: Loading plugin from JSON: vagrant-libvirt
INFO manager: Registered plugin: libvirt
INFO environment: Loading plugin from JSON: vagrant-mutate
INFO manager: Registered plugin: vagrant-mutate
INFO environment: Running hook: environment_load
INFO environment: Initializing config...
INFO loader: Set :default = "/opt/vagrant/embedded/gems/gems/vagrant-1.3.5/config/default.rb"
DEBUG loader: Populating proc cache for "/opt/vagrant/embedded/gems/gems/vagrant-1.3.5/config/default.rb"
DEBUG loader: Load procs for pathname: /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/config/default.rb
INFO loader: Set :root = #Pathname:/home/pittsb/vagrant/kvm-test/Vagrantfile
DEBUG loader: Populating proc cache for #Pathname:/home/pittsb/vagrant/kvm-test/Vagrantfile
DEBUG loader: Load procs for pathname: /home/pittsb/vagrant/kvm-test/Vagrantfile
INFO loader: Loading configuration in order: [:default, :home, :root]
DEBUG loader: Loading from: default (evaluating)
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG hosts: Host path search classes: [VagrantPlugins::HostRedHat::Host, VagrantPlugins::HostGentoo::Host, VagrantPlugins::HostWindows::Host, VagrantPlugins::HostArch::Host, VagrantPlugins::HostFreeBSD::Host, VagrantPlugins::HostSlackware::Host, VagrantPlugins::HostOpenSUSE::Host, VagrantPlugins::HostBSD::Host, VagrantPlugins::HostLinux::Host]
INFO hosts: Host class: VagrantPlugins::HostLinux::Host
INFO runner: Running action: #Vagrant::Action::Builder:0x000000016ff698
INFO cli: CLI: [] "up" ["--provider", "kvm"]
DEBUG cli: Invoking command class: VagrantPlugins::CommandUp::Command ["--provider", "kvm"]
DEBUG command: 'Up' each target VM...
DEBUG command: Getting target VMs for command. Arguments:
DEBUG command: -- names: []
DEBUG command: -- options: {:provider=>"kvm"}
DEBUG command: Loading all machines...
INFO environment: Getting machine: test_vm (kvm)
INFO environment: Uncached load of machine.
INFO loader: Set :vm_test_vm = [["2", #Proc:0x0000000110e518@/home/pittsb/vagrant/kvm-test/Vagrantfile:9]]
DEBUG loader: Populating proc cache for ["2", #Proc:0x0000000110e518@/home/pittsb/vagrant/kvm-test/Vagrantfile:9]
INFO loader: Loading configuration in order: [:default, :home, :root, :vm_test_vm]
DEBUG loader: Loading from: default (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: vm_test_vm (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO box_collection: Searching for box: debian-wheezy (kvm) in /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/metadata.json
INFO box_collection: Box found: debian-wheezy (kvm)
INFO environment: Box exists with Vagrantfile. Reloading machine config.
INFO loader: Set :"box_debian-wheezy_kvm" = #Pathname:/home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/Vagrantfile
DEBUG loader: Populating proc cache for #Pathname:/home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/Vagrantfile
DEBUG loader: Load procs for pathname: /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/Vagrantfile
INFO loader: Loading configuration in order: [:default, :"box_debian-wheezy_kvm", :home, :root, :vm_test_vm]
DEBUG loader: Loading from: default (cache)
DEBUG loader: Loading from: box_debian-wheezy_kvm (evaluating)
DEBUG loader: Upgrading config from version 1 to 2
DEBUG loader: Upgrading config to version 2
DEBUG loader: Upgraded to version 2 with 0 warnings and 0 errors
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: vm_test_vm (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO machine: Initializing machine: test_vm
INFO machine: - Provider: VagrantPlugins::ProviderKvm::Provider
INFO machine: - Box: #Vagrant::Box:0x00000000abff68
INFO machine: - Data dir: /home/pittsb/vagrant/kvm-test/.vagrant/machines/test_vm/kvm
INFO driver: Check KVM kernel modules
INFO command: With machine: test_vm (QEMU/KVM (new VM))
INFO interface: info: Bringing machine 'test_vm' up with 'kvm' provider...
INFO batch_action: Enabling parallelization by default.
INFO batch_action: Disabling parallelization because provider doesn't support it: kvm
INFO batch_action: Batch action will parallelize: false
INFO batch_action: Starting action: #Vagrant::Machine:0x00000000c6c2a8 up {:destroy_on_error=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil, :provider=>"kvm"}
INFO machine: Calling action: up on provider QEMU/KVM (new VM)
INFO runner: Running action: #Vagrant::Action::Builder:0x000000012a4260
INFO warden: Calling IN action: #VagrantPlugins::ProviderKvm::Action::CheckKvm:0x00000000ccea20
INFO driver: Check KVM kernel modules
INFO warden: Calling IN action: #VagrantPlugins::ProviderKvm::Action::SetName:0x00000000cce958
INFO setname: Setting the name of the VM: kvm-test_1386733567
INFO warden: Calling IN action: #Vagrant::Action::Builtin::ConfigValidate:0x000000010a6170
INFO warden: Calling IN action: #VagrantPlugins::ProviderKvm::Action::InitStoragePool:0x000000010a60d0
INFO driver: Check KVM kernel modules
INFO driver: Creating storage pool vagrant in /home/pittsb/.vagrant.d/tmp/storage-pool
INFO warden: Calling IN action: #Vagrant::Action::Builtin::Call:0x000000010a5fb8
INFO runner: Running action: #Vagrant::Action::Builder:0x00000001773ca0
INFO warden: Calling IN action: #VagrantPlugins::ProviderKvm::Action::Created:0x00000001771bd0
INFO warden: Calling OUT action: #VagrantPlugins::ProviderKvm::Action::Created:0x00000001771bd0
INFO runner: Running action: #Vagrant::Action::Warden:0x0000000178e7f8
INFO warden: Calling IN action: #VagrantPlugins::ProviderKvm::Action::CheckBox:0x0000000178e758
INFO box_collection: Searching for box: debian-wheezy (kvm) in /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/metadata.json
INFO box_collection: Box found: debian-wheezy (kvm)
INFO warden: Calling IN action: #VagrantPlugins::ProviderKvm::Action::Import:0x0000000178e730
INFO interface: info: Importing base box 'debian-wheezy'...
DEBUG import: Importing volume. Storage path: /home/pittsb/.vagrant.d/tmp/storage-pool Image Type: qcow2
INFO driver: Creating storage pool vagrant_1000_debian-wheezy in /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm
INFO driver: Creating volume box-disk1-1386733568.img backed by volume /home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/box-disk1.img
DEBUG driver: Creating volume with XML:

box-disk1-1386733568.img
0
42949672960

/home/pittsb/.vagrant.d/tmp/storage-pool/box-disk1-1386733568.img



/home/pittsb/.vagrant.d/boxes/debian-wheezy/kvm/box-disk1.img


ERROR warden: Error occurred: undefined method create_volume_xml' for nil:NilClass INFO warden: Beginning recovery process... INFO warden: Calling recover: #<VagrantPlugins::ProviderKvm::Action::Import:0x0000000178e730> INFO warden: Recovery complete. INFO warden: Beginning recovery process... INFO warden: Recovery complete. ERROR warden: Error occurred: undefined methodcreate_volume_xml' for nil:NilClass
INFO warden: Beginning recovery process...
INFO warden: Calling recover: #Vagrant::Action::Builtin::Call:0x000000010a5fb8
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
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 environment: Running hook: environment_unload
INFO runner: Running action: #Vagrant::Action::Builder:0x000000015e0028
/home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/driver/driver.rb:123:in create_volume': undefined methodcreate_volume_xml' for nil:NilClass (NoMethodError)
from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/import.rb:89:in import_volume' from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/import.rb:39:incall'
from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/warden.rb:34:in call' from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/check_box.rb:31:incall'
from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/warden.rb:34:in call' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/runner.rb:61:inblock in run'
from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/util/busy.rb:19:in busy' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/runner.rb:61:inrun'
from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/builtin/call.rb:51:in call' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/warden.rb:34:incall'
from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/init_storage_pool.rb:14:in call' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/warden.rb:34:incall'
from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/builtin/config_validate.rb:25:in call' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/warden.rb:34:incall'
from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/set_name.rb:25:in call' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/warden.rb:34:incall'
from /home/pittsb/.vagrant.d/gems/gems/vagrant-kvm-0.1.4/lib/vagrant-kvm/action/check_kvm.rb:18:in call' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/warden.rb:34:incall'
from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/builder.rb:116:in call' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/runner.rb:61:inblock in run'
from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/util/busy.rb:19:in busy' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/action/runner.rb:61:inrun'
from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/machine.rb:147:in action' from /opt/vagrant/embedded/gems/gems/vagrant-1.3.5/lib/vagrant/batch_action.rb:63:inblock (2 levels) in run'

@sciurus
Copy link
Contributor Author

sciurus commented Dec 11, 2013

After reverting 5bd991d I still have this error.

@ghost ghost assigned miurahr Dec 11, 2013
@adrahon adrahon mentioned this issue Jan 5, 2014
Closed
@miurahr
Copy link
Collaborator

miurahr commented Jan 6, 2014

Does #131 fix it?

@sciurus
Copy link
Contributor Author

sciurus commented Jan 21, 2014

Yes, that error has gone away. I now have a new error

qemu-system-x86_64: -drive file=/home/pittsb/.vagrant.d/tmp/storage-pool/box-disk1-1390266402.img,if=none,id=drive-virtio-disk0,format=qcow2: could not open disk image /home/pittsb/.vagrant.d/tmp/storage-pool/box-disk1-1390266402.img: Permission denied

The permissions on /home/pittsb/.vagrant.d/tmp/storage-pool are rw only for the owner, which is root. If you want full debug output in this or another issue, please let me know.

@sciurus
Copy link
Contributor Author

sciurus commented Jan 21, 2014

Actually I take that back, now I'm getting the create_volume_xml error again.

I think I get different errors depending on which directories and storage pools already exist or don't. I'll have to take some time to go through the combinations more carefully.

@miurahr
Copy link
Collaborator

miurahr commented Jan 21, 2014

Same here. I can reproduce it. Now looking for solution.

@miurahr
Copy link
Collaborator

miurahr commented Feb 7, 2014

@sciurus Does #152 fix disk-image owner and permission problem?

@miurahr
Copy link
Collaborator

miurahr commented Feb 25, 2014

#131 and #152 fixed this.
If there still be problem, please reopen.

@miurahr miurahr closed this as completed Feb 25, 2014
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@miurahr miurahr

Labels
bug
Projects
None yet
Milestone
0.1.5
Development

No branches or pull requests
3 participants
@miurahr @adrahon @sciurus