[one-users] [VMM][E]: deploy_action, error getting driver vm_kvm

Hector Sanjuan hsanjuan at opennebula.org
Wed Feb 8 11:21:15 PST 2012


Id say you added you host with "vm_kvm" instead of "vmm_kvm"


--
Hector Sanjuan
Opennebula developer

-------- Original message --------
Subject: [one-users] [VMM][E]: deploy_action, error getting driver vm_kvm 
From: Hendrik Wißmann <wissmann at gmx.de> 
To: users at lists.opennebula.org 
CC:  

Hello,

I'm trying to evaluate openNebula 3.2.0 (Centos 6.1) using KVM. Doing so
I chose a two node installation environment with one frontend and one
KVM server with the following packages installed:

KVM:

libvirt-client-0.9.4-23.el6_2.4.x86_64
libvirt-devel-0.9.4-23.el6_2.4.x86_64
libvirt-python-0.9.4-23.el6_2.4.x86_64
libvirt-0.9.4-23.el6_2.4.x86_64
qemu-img-0.12.1.2-2.209.el6_2.4.x86_64
qemu-kvm-0.12.1.2-2.209.el6_2.4.x86_64
qemu-kvm-tools-0.12.1.2-2.209.el6_2.4.x86_64
gpxe-roms-qemu-0.9.7-6.9.el6.noarch
ruby-libs-1.8.7.352-3.el6.x86_64
ruby-1.8.7.352-3.el6.x86_64

Modifications of the following configuration files:

/etc/libvirt/qemu.conf
user = "oneadmin"
group = "oneadmin"
dynamic_ownership = 0
unix_sock_rw_perms = "0777"
auth_unix_ro = "none"
auth_unix_rw = "none"

/etc/libvirt/libvirtd.conf
listen-tcp = 1

File doesn't exist, but it should be an ubuntu file:
/etc/default/libvirt-bin : add -l option to libvirtd_opts

/etc/polkit-1/localauthority/50-local.d/50-org.example-libvirt-remote-access.pkla
[libvirt Management Access]
  Identity=unix-group:oneadmin
  Action=org.libvirt.unix.manage
  ResultAny=yes
  ResultInactive=yes
  ResultActive=yes



frontend:

ruby-irb-1.8.7.352-4.el6_2.x86_64
rubygem-rack-test-0.5.4-1.el6.noarch
ruby-libs-1.8.7.352-4.el6_2.x86_64
ruby-devel-1.8.7.352-4.el6_2.x86_64
ruby-rdoc-1.8.7.352-4.el6_2.x86_64
rubygem-sinatra-1.0-2.el6.noarch
rubygems-1.3.7-1.el6.noarch
ruby-1.8.7.352-4.el6_2.x86_64
rubygem-rack-1.1.0-2.el6.noarch


After configuring, setting up a NFS-server (/var/lib/one) and mounting 
the directory in
the same place on the KVM server, I registered the host, an image, a 
template and
a network-configuration:

onehost create centos-2 im_kvm vm_kvm tm_shared ovswitch

Image
NAME          = "CentOS6"
PATH          = /onerep/centos.img
TYPE          = OS
DESCRIPTION   = "CentOS 6.1 Server"


Network
NAME    = "First Lan"
TYPE    = FIXED
BRIDGE  = br0
LEASES  = [IP=xxx.yyy.zzz.115]
LEASES  = [IP=xxx.yyy.zzz.116]


NAME = CentOS-Templ

CPU    = 1
MEMORY = 512

# --- kernel & boot device ---

OS = [
   kernel   = "/vmlinuz",
   initrd   = "/initrd.img",
   root     = "sda" ]

# --- 3 disks ---

DISK = [ IMAGE_ID  = 13 ]
DISK = [
   type     = swap,
   size     = 1024,
   readonly = "no" ]

# --- 1 NIC ---

NIC = [ NETWORK_ID = 0 ]

# --- Placement options ---

REQUIREMENTS = "CPUSPEED > 1000"
RANK         = FREECPU


The start of a virtual machine results in an error:

Wed Feb  8 19:20:13 2012 [ONE][I]: Starting OpenNebula 3.2.0
----------------------------------------
      OpenNebula Configuration File
----------------------------------------
AUTH_MAD=ARGUMENTS=--authn 
ssh,x509,ldap,server_cipher,server_x509,EXECUTABLE=one_auth_mad
DB=BACKEND=sqlite
DEBUG_LEVEL=3
DEFAULT_DEVICE_PREFIX=hd
DEFAULT_IMAGE_TYPE=OS
ENABLE_OTHER_PERMISSIONS=YES
HM_MAD=EXECUTABLE=one_hm
HOST_MONITORING_INTERVAL=600
HOST_PER_INTERVAL=15
IMAGE_MAD=ARGUMENTS=fs -t 15,EXECUTABLE=one_image
IM_MAD=ARGUMENTS=-r 0 -t 15 
kvm,EXECUTABLE=one_im_ssh,NAME=im_kvm,ONE_MAD_DEBUG=1
MAC_PREFIX=02:00
MANAGER_TIMER=15
NETWORK_SIZE=254
ONE_MAD_DEBUG=1
PORT=2633
SCRIPTS_REMOTE_DIR=/var/tmp/one
SESSION_EXPIRATION_TIME=900
TM_MAD=ARGUMENTS=tm_shared/tm_shared.conf,EXECUTABLE=one_tm,NAME=tm_shared
VM_DIR=/var/lib/one/
VM_MAD=ARGUMENTS=-t 15 -r 0 
kvm,DEFAULT=vmm_exec/vmm_exec_kvm.conf,EXECUTABLE=one_vmm_exec,NAME=vmm_kvm,TYPE=kvm
VM_PER_INTERVAL=5
VM_POLLING_INTERVAL=600
VNC_BASE_PORT=5900
----------------------------------------
Wed Feb  8 19:20:13 2012 [ONE][I]: Log level:3 
[0=ERROR,1=WARNING,2=INFO,3=DEBUG]
Wed Feb  8 19:20:13 2012 [ONE][I]: Checking database version.
Wed Feb  8 19:20:13 2012 [VMM][I]: Starting Virtual Machine Manager...
Wed Feb  8 19:20:13 2012 [VMM][I]: Virtual Machine Manager started.
Wed Feb  8 19:20:13 2012 [LCM][I]: Starting Life-cycle Manager...
Wed Feb  8 19:20:13 2012 [LCM][I]: Life-cycle Manager started.
Wed Feb  8 19:20:13 2012 [InM][I]: Starting Information Manager...
Wed Feb  8 19:20:13 2012 [InM][I]: Information Manager started.
Wed Feb  8 19:20:13 2012 [TrM][I]: Starting Transfer Manager...
Wed Feb  8 19:20:13 2012 [TrM][I]: Transfer Manager started.
Wed Feb  8 19:20:13 2012 [DiM][I]: Starting Dispatch Manager...
Wed Feb  8 19:20:13 2012 [DiM][I]: Dispatch Manager started.
Wed Feb  8 19:20:13 2012 [HKM][I]: Starting Hook Manager...
Wed Feb  8 19:20:13 2012 [HKM][I]: Hook Manager started.
Wed Feb  8 19:20:13 2012 [AuM][I]: Starting Auth Manager...
Wed Feb  8 19:20:13 2012 [AuM][I]: Authorization Manager started.
Wed Feb  8 19:20:13 2012 [ImM][I]: Starting Image Manager...
Wed Feb  8 19:20:13 2012 [ImM][I]: Image Manager started.
Wed Feb  8 19:20:13 2012 [ReM][I]: Starting Request Manager...
Wed Feb  8 19:20:13 2012 [ReM][I]: Starting XML-RPC server, port 2633 ...
Wed Feb  8 19:20:13 2012 [ReM][I]: Request Manager started.
Wed Feb  8 19:20:15 2012 [VMM][I]: Loading Virtual Machine Manager drivers.
Wed Feb  8 19:20:15 2012 [VMM][I]:      Loading driver: vmm_kvm (KVM)
Wed Feb  8 19:20:15 2012 [VMM][I]:      Driver vmm_kvm loaded.
Wed Feb  8 19:20:15 2012 [InM][I]: Loading Information Manager drivers.
Wed Feb  8 19:20:15 2012 [InM][I]:      Loading driver: im_kvm
Wed Feb  8 19:20:15 2012 [InM][I]:      Driver im_kvm loaded
Wed Feb  8 19:20:15 2012 [TM][I]: Loading Transfer Manager drivers.
Wed Feb  8 19:20:15 2012 [VMM][I]:      Loading driver: tm_shared
Wed Feb  8 19:20:15 2012 [TM][I]:       Driver tm_shared loaded.
Wed Feb  8 19:20:15 2012 [HKM][I]: Loading Hook Manager driver.
Wed Feb  8 19:20:15 2012 [HKM][I]:      Hook Manager loaded
Wed Feb  8 19:20:15 2012 [ImM][I]: Loading Image Manager driver.
Wed Feb  8 19:20:15 2012 [ImM][I]:      Image Manager loaded
Wed Feb  8 19:20:15 2012 [AuM][I]: Loading Auth. Manager driver.
Wed Feb  8 19:20:15 2012 [AuM][I]:      Auth Manager loaded
Wed Feb  8 19:20:20 2012 [ReM][D]: VirtualMachinePoolInfo method invoked
Wed Feb  8 19:20:28 2012 [InM][I]: Monitoring host centos-2 (5)
Wed Feb  8 19:20:31 2012 [InM][I]: ExitCode: 0
Wed Feb  8 19:20:31 2012 [InM][D]: Host 5 successfully monitored.
Wed Feb  8 19:20:43 2012 [ReM][D]: HostPoolInfo method invoked
Wed Feb  8 19:20:43 2012 [ReM][D]: VirtualMachinePoolInfo method invoked
Wed Feb  8 19:20:43 2012 [ReM][D]: AclInfo method invoked
Wed Feb  8 19:20:43 2012 [ReM][D]: HostPoolInfo method invoked
Wed Feb  8 19:20:53 2012 [ReM][D]: HostInfo method invoked
Wed Feb  8 19:21:13 2012 [ReM][D]: HostPoolInfo method invoked
Wed Feb  8 19:21:13 2012 [ReM][D]: VirtualMachinePoolInfo method invoked
Wed Feb  8 19:21:13 2012 [ReM][D]: AclInfo method invoked
Wed Feb  8 19:21:13 2012 [ReM][D]: UserPoolInfo method invoked
Wed Feb  8 19:21:13 2012 [AuM][D]: Message received: LOG I 0 ExitCode: 0
....
Wed Feb  8 19:22:13 2012 [ReM][D]: HostPoolInfo method invoked
Wed Feb  8 19:22:13 2012 [ReM][D]: VirtualMachinePoolInfo method invoked
Wed Feb  8 19:22:13 2012 [ReM][D]: AclInfo method invoked
Wed Feb  8 19:22:13 2012 [ReM][D]: VirtualMachineDeploy method invoked
Wed Feb  8 19:22:13 2012 [DiM][D]: Deploying VM 20
Wed Feb  8 19:22:17 2012 [ReM][D]: GroupPoolInfo method invoked
Wed Feb  8 19:22:17 2012 [AuM][D]: Message received: LOG I 21 ExitCode: 0

Wed Feb  8 19:22:17 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:17 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 21 -

Wed Feb  8 19:22:21 2012 [ReM][D]: HostPoolInfo method invoked
Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG D 20 
tm_clone.sh: 
centos-1.fernuni-hagen.de:/var/lib/one/images/6ff900f642a88eede3277db946a617c3 
centos-2:/var/lib/one//20/images/disk.0

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG D 20 
tm_clone.sh: DST: /var/lib/one//20/images/disk.0

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_clone.sh: Creating directory /var/lib/one//20/images

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_clone.sh: Executed "mkdir -p /var/lib/one//20/images".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_clone.sh: Executed "chmod a+w /var/lib/one//20/images".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_clone.sh: Cloning /var/lib/one/images/6ff900f642a88eede3277db946a617c3

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_clone.sh: Executed "cp -r 
/var/lib/one/images/6ff900f642a88eede3277db946a617c3 
/var/lib/one//20/images/disk.0".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_clone.sh: Executed "chmod a+rw /var/lib/one//20/images/disk.0".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 ExitCode: 0

Wed Feb  8 19:22:26 2012 [AuM][D]: Message received: LOG I 22 ExitCode: 0

Wed Feb  8 19:22:26 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:26 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 22 -

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG D 20 
tm_mkswap.sh: Creating directory /var/lib/one//20/images

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_mkswap.sh: Executed "mkdir -p /var/lib/one//20/images".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_mkswap.sh: Executed "chmod a+w /var/lib/one//20/images".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG D 20 
tm_mkswap.sh: Creating 1024Mb image in /var/lib/one//20/images/disk.1

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_mkswap.sh: Executed "dd if=/dev/zero 
of=/var/lib/one//20/images/disk.1 bs=1 count=1 seek=1024M".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG D 20 
tm_mkswap.sh: Initializing swap space

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_mkswap.sh: Executed "mkswap /var/lib/one//20/images/disk.1".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 
tm_mkswap.sh: Executed "chmod a+w /var/lib/one//20/images/disk.1".

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: LOG I 20 ExitCode: 0

Wed Feb  8 19:22:26 2012 [TM][D]: Message received: TRANSFER SUCCESS 20 -

Wed Feb  8 19:22:29 2012 [ReM][D]: ImagePoolInfo method invoked
Wed Feb  8 19:22:29 2012 [AuM][D]: Message received: LOG I 23 ExitCode: 0

Wed Feb  8 19:22:29 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:29 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 23 -

Wed Feb  8 19:22:31 2012 [ReM][D]: TemplatePoolInfo method invoked
Wed Feb  8 19:22:31 2012 [AuM][D]: Message received: LOG I 24 ExitCode: 0

Wed Feb  8 19:22:31 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:31 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 24 -

Wed Feb  8 19:22:34 2012 [ReM][D]: VirtualMachinePoolInfo method invoked
Wed Feb  8 19:22:34 2012 [AuM][D]: Message received: LOG I 25 ExitCode: 0

Wed Feb  8 19:22:34 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:34 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 25 -

Wed Feb  8 19:22:37 2012 [ReM][D]: AclInfo method invoked
Wed Feb  8 19:22:38 2012 [AuM][D]: Message received: LOG I 26 ExitCode: 0

Wed Feb  8 19:22:38 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:38 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 26 -

Wed Feb  8 19:22:38 2012 [AuM][D]: Message received: AUTHORIZE SUCCESS 27 -

Wed Feb  8 19:22:38 2012 [ReM][D]: UserPoolInfo method invoked
Wed Feb  8 19:22:38 2012 [AuM][D]: Message received: LOG I 28 ExitCode: 0

Wed Feb  8 19:22:38 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:38 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 28 -

Wed Feb  8 19:22:41 2012 [ReM][D]: VirtualNetworkPoolInfo method invoked
Wed Feb  8 19:22:41 2012 [AuM][D]: Message received: LOG I 29 ExitCode: 0

Wed Feb  8 19:22:41 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:41 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 29 -

Wed Feb  8 19:22:43 2012 [ReM][D]: HostPoolInfo method invoked
Wed Feb  8 19:22:43 2012 [ReM][D]: VirtualMachinePoolInfo method invoked
Wed Feb  8 19:22:43 2012 [ReM][D]: AclInfo method invoked
Wed Feb  8 19:22:57 2012 [ReM][D]: VirtualMachineInfo method invoked
Wed Feb  8 19:22:57 2012 [AuM][D]: Message received: LOG I 30 ExitCode: 0

Wed Feb  8 19:22:57 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:57 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 30 -

Wed Feb  8 19:22:57 2012 [AuM][D]: Message received: AUTHORIZE SUCCESS 31 -

Wed Feb  8 19:22:57 2012 [ReM][D]: VirtualMachineInfo method invoked
Wed Feb  8 19:22:57 2012 [AuM][D]: Message received: LOG I 32 ExitCode: 0

Wed Feb  8 19:22:57 2012 [AuM][I]: ExitCode: 0
Wed Feb  8 19:22:57 2012 [AuM][D]: Message received: AUTHENTICATE 
SUCCESS 32 -

Wed Feb  8 19:22:57 2012 [AuM][D]: Message received: AUTHORIZE SUCCESS 33 -

Wed Feb  8 19:23:13 2012 [ReM][D]: HostPoolInfo method invoked
Wed Feb  8 19:23:13 2012 [ReM][D]: VirtualMachinePoolInfo method invoked
Wed Feb  8 19:23:13 2012 [ReM][D]: AclInfo method invoked
Wed Feb  8 19:23:20 2012 [ReM][D]: GroupPoolInfo method invoked
Wed Feb  8 19:23:20 2012 [AuM][D]: Message received: LOG I 34 ExitCode: 0


Wed Feb  8 19:22:13 2012 [DiM][I]: New VM state is ACTIVE.
Wed Feb  8 19:22:13 2012 [LCM][I]: New VM state is PROLOG.
Wed Feb  8 19:22:13 2012 [VM][I]: Virtual Machine has no context
Wed Feb  8 19:22:26 2012 [TM][D]: tm_clone.sh: 
centos-1:/var/lib/one/images/6ff900f642a88eede3277db946a617c3 
centos-2:/var/lib/one//20/images/disk.0
Wed Feb  8 19:22:26 2012 [TM][D]: tm_clone.sh: DST: 
/var/lib/one//20/images/disk.0
Wed Feb  8 19:22:26 2012 [TM][I]: tm_clone.sh: Creating directory 
/var/lib/one//20/images
Wed Feb  8 19:22:26 2012 [TM][I]: tm_clone.sh: Executed "mkdir -p 
/var/lib/one//20/images".
Wed Feb  8 19:22:26 2012 [TM][I]: tm_clone.sh: Executed "chmod a+w 
/var/lib/one//20/images".
Wed Feb  8 19:22:26 2012 [TM][I]: tm_clone.sh: Cloning 
/var/lib/one/images/6ff900f642a88eede3277db946a617c3
Wed Feb  8 19:22:26 2012 [TM][I]: tm_clone.sh: Executed "cp -r 
/var/lib/one/images/6ff900f642a88eede3277db946a617c3 
/var/lib/one//20/images/disk.0".
Wed Feb  8 19:22:26 2012 [TM][I]: tm_clone.sh: Executed "chmod a+rw 
/var/lib/one//20/images/disk.0".
Wed Feb  8 19:22:26 2012 [TM][I]: ExitCode: 0
Wed Feb  8 19:22:26 2012 [TM][D]: tm_mkswap.sh: Creating directory 
/var/lib/one//20/images
Wed Feb  8 19:22:26 2012 [TM][I]: tm_mkswap.sh: Executed "mkdir -p 
/var/lib/one//20/images".
Wed Feb  8 19:22:26 2012 [TM][I]: tm_mkswap.sh: Executed "chmod a+w 
/var/lib/one//20/images".
Wed Feb  8 19:22:26 2012 [TM][D]: tm_mkswap.sh: Creating 1024Mb image in 
/var/lib/one//20/images/disk.1
Wed Feb  8 19:22:26 2012 [TM][I]: tm_mkswap.sh: Executed "dd 
if=/dev/zero of=/var/lib/one//20/images/disk.1 bs=1 count=1 seek=1024M".
Wed Feb  8 19:22:26 2012 [TM][D]: tm_mkswap.sh: Initializing swap space
Wed Feb  8 19:22:26 2012 [TM][I]: tm_mkswap.sh: Executed "mkswap 
/var/lib/one//20/images/disk.1".
Wed Feb  8 19:22:26 2012 [TM][I]: tm_mkswap.sh: Executed "chmod a+w 
/var/lib/one//20/images/disk.1".
Wed Feb  8 19:22:26 2012 [TM][I]: ExitCode: 0
Wed Feb  8 19:22:26 2012 [LCM][I]: New VM state is BOOT
Wed Feb  8 19:22:26 2012 [VMM][E]: deploy_action, error getting driver 
vm_kvm
Wed Feb  8 19:22:26 2012 [DiM][I]: New VM state is FAILED

Google or the email archive show no solution, or I didn't find any clue.
In my opinion, the frontend doesn't connect the KVM-node to execute the 
virtual machine.
Does anybody know a solution or can give me some tips.

Regards Hendrik
_______________________________________________
Users mailing list
Users at lists.opennebula.org
http://lists.opennebula.org/listinfo.cgi/users-opennebula.org
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opennebula.org/pipermail/users-opennebula.org/attachments/20120208/b6a8f703/attachment-0003.htm>


More information about the Users mailing list