[one-users] Fail state attempting to deploy VM

Filippo Dalla Gassa pippo.dallagassa at gmail.com
Tue Dec 14 11:14:01 PST 2010


Hi,
I have a problem when I try to deploy my VM. I create the VM with onevm 
create and the VM stays in pending state, so with onevm deploy I try to 
deploy it but it immediatly change to fail state.

Here is my situation:
Front end:

    * OpenNebula 2.0 running on Ubuntu 10.04 Server
    * VMware Driver Addons with libvirt to communicate with the cluster
    * Shared NFS as configuration for the storage

Cluster:

    * Ubuntu 10.04 Desktop
    * VMware Server 2.0.2

To configure OpenNebula I followed the documentation on the site, with 
oneadmin's home directory in /srv/cloud/. I've chosen /srv/cloud/one/var 
as VM_DIR and I've exported it as NFS share.

Here is my oned.log file:

    Tue Dec 14 17:13:04 2010 [ONE][I]: Init OpenNebula Log system
    Tue Dec 14 17:13:04 2010 [ONE][I]: Log Level: 3
    [0=ERROR,1=WARNING,2=INFO,3=DEBUG]
    Tue Dec 14 17:13:04 2010 [ONE][I]:
    ----------------------------------------
    Tue Dec 14 17:13:04 2010 [ONE][I]:      OpenNebula Configuration File
    Tue Dec 14 17:13:04 2010 [ONE][I]:
    ----------------------------------------
    Tue Dec 14 17:13:04 2010 [ONE][I]:
    ----------------------------------
    DB=BACKEND=mysql,DB_NAME=opennebula,PASSWD=oneadmin,PORT=0,SERVER=localhost,USER=oneadmin
    DEBUG_LEVEL=3
    DEFAULT_DEVICE_PREFIX=hd
    DEFAULT_IMAGE_TYPE=OS
    HM_MAD=EXECUTABLE=one_hm
    HOST_MONITORING_INTERVAL=30
    IMAGE_REPOSITORY_PATH=$ONE_LOCATION/var/images
    IM_MAD=ARGUMENTS=kvm,EXECUTABLE=one_im_ssh,NAME=im_kvm
    IM_MAD=ARGUMENTS=vmware,EXECUTABLE=one_im_sh,NAME=im_vmware
    MAC_PREFIX=02:00
    MANAGER_TIMER=15
    NETWORK_SIZE=254
    PORT=2633
    SCRIPTS_REMOTE_DIR=/var/tmp/one
    TM_MAD=ARGUMENTS=tm_nfs/tm_nfs.conf,EXECUTABLE=one_tm,NAME=tm_nfs
    TM_MAD=ARGUMENTS=tm_vmware/tm_vmware.conf,EXECUTABLE=one_tm,NAME=tm_vmware
    VM_DIR=$ONE_LOCATION/var
    VM_HOOK=ARGUMENTS=$VMID,COMMAND=image.rb,NAME=image,ON=DONE
    VM_MAD=ARGUMENTS=kvm,DEFAULT=vmm_ssh/vmm_ssh_kvm.conf,EXECUTABLE=one_vmm_ssh,NAME=vmm_kvm,TYPE=kvm
    VM_MAD=ARGUMENTS=vmware,DEFAULT=vmm_sh/vmm_sh_vmware.conf,EXECUTABLE=one_vmm_sh,NAME=vmm_vmware,TYPE=vmware
    VM_POLLING_INTERVAL=30
    VNC_BASE_PORT=5900
    ----------------------------------
    Tue Dec 14 17:13:04 2010 [ONE][I]: Bootstraping OpenNebula database.
    Tue Dec 14 17:13:04 2010 [VMM][I]: Starting Virtual Machine Manager...
    Tue Dec 14 17:13:04 2010 [LCM][I]: Starting Life-cycle Manager...
    Tue Dec 14 17:13:04 2010 [InM][I]: Starting Information Manager...
    Tue Dec 14 17:13:04 2010 [TrM][I]: Starting Transfer Manager...
    Tue Dec 14 17:13:04 2010 [DiM][I]: Starting Dispatch Manager...
    Tue Dec 14 17:13:04 2010 [ReM][I]: Starting Request Manager...
    Tue Dec 14 17:13:04 2010 [InM][I]: Information Manager started.
    Tue Dec 14 17:13:04 2010 [TrM][I]: Transfer Manager started.
    Tue Dec 14 17:13:04 2010 [DiM][I]: Dispatch Manager started.
    Tue Dec 14 17:13:04 2010 [LCM][I]: Life-cycle Manager started.
    Tue Dec 14 17:13:04 2010 [VMM][I]: Virtual Machine Manager started.
    Tue Dec 14 17:13:04 2010 [ReM][I]: Starting XML-RPC server, port
    2633 ...
    Tue Dec 14 17:13:04 2010 [HKM][I]: Starting Hook Manager...
    Tue Dec 14 17:13:04 2010 [HKM][I]: Hook Manager started.
    Tue Dec 14 17:13:04 2010 [ReM][I]: Request Manager started.
    Tue Dec 14 17:13:06 2010 [VMM][I]: Loading Virtual Machine Manager
    drivers.
    Tue Dec 14 17:13:06 2010 [VMM][I]:     Loading driver: vmm_kvm (KVM)
    Tue Dec 14 17:13:06 2010 [VMM][I]:     Driver vmm_kvm loaded.
    Tue Dec 14 17:13:06 2010 [VMM][I]:     Loading driver: vmm_vmware
    (VMWARE)
    Tue Dec 14 17:13:07 2010 [VMM][I]:     Driver vmm_vmware loaded.
    Tue Dec 14 17:13:07 2010 [InM][I]: Loading Information Manager drivers.
    Tue Dec 14 17:13:07 2010 [InM][I]:     Loading driver: im_kvm
    Tue Dec 14 17:13:07 2010 [InM][I]:     Driver im_kvm loaded
    Tue Dec 14 17:13:07 2010 [InM][I]:     Loading driver: im_vmware
    Tue Dec 14 17:13:07 2010 [InM][I]:     Driver im_vmware loaded
    Tue Dec 14 17:13:07 2010 [TM][I]: Loading Transfer Manager drivers.
    Tue Dec 14 17:13:07 2010 [VMM][I]:     Loading driver: tm_nfs
    Tue Dec 14 17:13:07 2010 [TM][I]:     Driver tm_nfs loaded.
    Tue Dec 14 17:13:07 2010 [VMM][I]:     Loading driver: tm_vmware
    Tue Dec 14 17:13:07 2010 [TM][I]:     Driver tm_vmware loaded.
    Tue Dec 14 17:13:07 2010 [HKM][I]: Loading Hook Manager driver.
    Tue Dec 14 17:13:07 2010 [HKM][I]:     Hook Manager loaded
    Tue Dec 14 17:13:11 2010 [ReM][D]: VirtualMachinePoolInfo method invoked
    Tue Dec 14 17:13:19 2010 [InM][I]: Monitoring host cluster (0)
    Tue Dec 14 17:13:19 2010 [InM][D]: Host 0 successfully monitored.
    Tue Dec 14 17:13:33 2010 [ReM][D]: VirtualMachineAllocate invoked
    Tue Dec 14 17:13:35 2010 [ReM][D]: HostPoolInfo method invoked
    Tue Dec 14 17:13:35 2010 [ReM][D]: VirtualMachinePoolInfo method invoked
    Tue Dec 14 17:13:45 2010 [ReM][D]: VirtualMachinePoolInfo method invoked
    Tue Dec 14 17:13:49 2010 [InM][I]: Monitoring host cluster (0)
    Tue Dec 14 17:13:49 2010 [InM][D]: Host 0 successfully monitored.
    Tue Dec 14 17:14:05 2010 [ReM][D]: HostPoolInfo method invoked
    Tue Dec 14 17:14:05 2010 [ReM][D]: VirtualMachinePoolInfo method invoked
    Tue Dec 14 17:14:08 2010 [ReM][D]: HostPoolInfo method invoked
    Tue Dec 14 17:14:08 2010 [ReM][D]: VirtualMachinePoolInfo method invoked
    Tue Dec 14 17:14:08 2010 [ReM][D]: VirtualMachineDeploy invoked
    Tue Dec 14 17:14:08 2010 [DiM][D]: Deploying VM 13
    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: LOG - 13
    tm_clone.sh:
    filippo-server:/srv/cloud/one/one-templates/ttylinux.img
    cluster:/srv/cloud/one/var/13/images/disk.0

    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: LOG - 13
    tm_clone.sh: DST: /srv/cloud/one/var/13/images/disk.0

    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: LOG - 13
    tm_clone.sh: Creating directory /srv/cloud/one/var/13/images

    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: LOG - 13
    tm_clone.sh: Executed "mkdir -p /srv/cloud/one/var/13/images".

    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: LOG - 13
    tm_clone.sh: Executed "chmod a+w /srv/cloud/one/var/13/images".

    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: LOG - 13
    tm_clone.sh: Cloning /srv/cloud/one/one-templates/ttylinux.img

    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: LOG - 13
    tm_clone.sh: Executed "cp -r
    /srv/cloud/one/one-templates/ttylinux.img
    /srv/cloud/one/var/13/images/disk.0".

    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: LOG - 13
    tm_clone.sh: Executed "chmod a+rw /srv/cloud/one/var/13/images/disk.0".

    Tue Dec 14 17:14:09 2010 [TM][D]: Message received: TRANSFER SUCCESS
    13 -

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: LOG - 13
    Command execution fail: /srv/cloud/one/lib/remotes/vmm/vmware/deploy
    cluster /srv/cloud/one/var/13/deployment.0

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: LOG - 13 STDERR
    follows.

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: LOG - 13
    [VMWARE] cmd failed [/srv/cloud/one/bin/tty_expect -u oneadmin -p
    oneadmin virsh -c gsx://cluster?no_verify=1 define
    /srv/cloud/one/var/13/deployment.0]. Stderr:

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: LOG - 13
    errore: Impossibile definire il dominio da
    /srv/cloud/one/var/13/deployment.0

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: LOG - 13
    errore: errore interno HTTP response code 500 for upload to
    'https://cluster:8333/folder/13%2fimages%2fdisk%2e0/one%2d13.vmx?dcPath=ha%2ddatacenter&dsName=images'

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: LOG - 13

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: LOG - 13 .
    Stdout: ExitCode: 1

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: LOG - 13
    ExitCode: 1

    Tue Dec 14 17:14:11 2010 [VMM][D]: Message received: DEPLOY FAILURE 13 -

    Tue Dec 14 17:14:11 2010 [TM][D]: Message received: LOG - 13
    tm_delete.sh: Deleting /srv/cloud/one/var/13/images

    Tue Dec 14 17:14:11 2010 [TM][D]: Message received: LOG - 13
    tm_delete.sh: Executed "rm -rf /srv/cloud/one/var/13/images".

    Tue Dec 14 17:14:11 2010 [TM][D]: Message received: TRANSFER SUCCESS
    13 -

And here is my vm.log file:

    Tue Dec 14 17:14:08 2010 [DiM][I]: New VM state is ACTIVE.
    Tue Dec 14 17:14:08 2010 [LCM][I]: New VM state is PROLOG.
    Tue Dec 14 17:14:08 2010 [VM][I]: Virtual Machine has no context
    Tue Dec 14 17:14:09 2010 [TM][I]: tm_clone.sh:
    filippo-server:/srv/cloud/one/one-templates/ttylinux.img
    cluster:/srv/cloud/one/var/13/images/disk.0
    Tue Dec 14 17:14:09 2010 [TM][I]: tm_clone.sh: DST:
    /srv/cloud/one/var/13/images/disk.0
    Tue Dec 14 17:14:09 2010 [TM][I]: tm_clone.sh: Creating directory
    /srv/cloud/one/var/13/images
    Tue Dec 14 17:14:09 2010 [TM][I]: tm_clone.sh: Executed "mkdir -p
    /srv/cloud/one/var/13/images".
    Tue Dec 14 17:14:09 2010 [TM][I]: tm_clone.sh: Executed "chmod a+w
    /srv/cloud/one/var/13/images".
    Tue Dec 14 17:14:09 2010 [TM][I]: tm_clone.sh: Cloning
    /srv/cloud/one/one-templates/ttylinux.img
    Tue Dec 14 17:14:09 2010 [TM][I]: tm_clone.sh: Executed "cp -r
    /srv/cloud/one/one-templates/ttylinux.img
    /srv/cloud/one/var/13/images/disk.0".
    Tue Dec 14 17:14:09 2010 [TM][I]: tm_clone.sh: Executed "chmod a+rw
    /srv/cloud/one/var/13/images/disk.0".
    Tue Dec 14 17:14:09 2010 [LCM][I]: New VM state is BOOT
    Tue Dec 14 17:14:09 2010 [VMM][I]: Generating deployment file:
    /srv/cloud/one/var/13/deployment.0
    Tue Dec 14 17:14:11 2010 [VMM][I]: Command execution fail:
    /srv/cloud/one/lib/remotes/vmm/vmware/deploy cluster
    /srv/cloud/one/var/13/deployment.0
    Tue Dec 14 17:14:11 2010 [VMM][I]: STDERR follows.
    Tue Dec 14 17:14:11 2010 [VMM][I]: [VMWARE] cmd failed
    [/srv/cloud/one/bin/tty_expect -u oneadmin -p oneadmin virsh -c
    gsx://cluster?no_verify=1 define
    /srv/cloud/one/var/13/deployment.0]. Stderr:
    Tue Dec 14 17:14:11 2010 [VMM][I]: errore: Impossibile definire il
    dominio da /srv/cloud/one/var/13/deployment.0
    Tue Dec 14 17:14:11 2010 [VMM][I]: errore: errore interno HTTP
    response code 500 for upload to
    'https://cluster:8333/folder/13%2fimages%2fdisk%2e0/one%2d13.vmx?dcPath=ha%2ddatacenter&dsName=images'
    Tue Dec 14 17:14:11 2010 [VMM][I]:
    Tue Dec 14 17:14:11 2010 [VMM][I]: . Stdout: ExitCode: 1
    Tue Dec 14 17:14:11 2010 [VMM][I]: ExitCode: 1
    Tue Dec 14 17:14:11 2010 [VMM][E]: Error deploying virtual machine
    Tue Dec 14 17:14:11 2010 [DiM][I]: New VM state is FAILED
    Tue Dec 14 17:14:11 2010 [TM][W]: Ignored: LOG - 13 tm_delete.sh:
    Deleting /srv/cloud/one/var/13/images

    Tue Dec 14 17:14:11 2010 [TM][W]: Ignored: LOG - 13 tm_delete.sh:
    Executed "rm -rf /srv/cloud/one/var/13/images".

    Tue Dec 14 17:14:11 2010 [TM][W]: Ignored: TRANSFER SUCCESS 13 -

As you can see from log files I'm trying to create and deploy a VM using 
ttylinux package from the site.
Can someone help me to solve the problem? And can you explain me what is 
the "domain" that virsh tries to define from the file deployment.0? And 
why is it impossible to define it?
Here is deployment.0:

    <domain type='vmware'>
    <name>one-13</name>
    <memory>65536</memory>
    <os>
    <type arch='i686'>hvm</type>
    </os>
    <devices>
    <disk type='file' device='disk'>
    <source file='[images] 13/images/disk.0/disk.vmdk'/>
    <target dev='hda'/>
    </disk>
    <interface type='bridge'>
    <source bridge='eth0'/>
    <mac address='02:00:c0:a8:02:2d'/>
    </interface>
    </devices>
    </domain>

I hope I was clear and provided the necessary information.
Regards,

-- 
Filippo Dalla Gassa

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opennebula.org/pipermail/users-opennebula.org/attachments/20101214/8df0289d/attachment-0002.htm>


More information about the Users mailing list