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

Wed Feb 8 11:49:16 PST 2012

Thank you very much; it works and now kvm reports an error, but that's
another story ;-)

Hendrik

On 08.02.2012 20:21, Hector Sanjuan wrote:
> 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