Unable to deploy VM: (Command execution fail: cat << EOT | /var/tmp/one/vmm/kvm/deploy)

Hi, I’m trying to deploy a VM in OpenNebula, but always running into the same problem. I tailed the /var/log/one/oned.log and the error message is as below:

Wed Nov 30 11:16:04 2016 [Z0][ReM][D]: Req:1008 UID:0 TemplateInfo invoked , 2
Wed Nov 30 11:16:04 2016 [Z0][ReM][D]: Req:1008 UID:0 TemplateInfo result SUCCESS, "2</I…"
Wed Nov 30 11:16:04 2016 [Z0][ReM][D]: Req:6368 UID:0 TemplateInstantiate invoked , 2, “nov30 test”, false, ""
Wed Nov 30 11:16:04 2016 [Z0][ReM][D]: Req:6368 UID:0 TemplateInstantiate result SUCCESS, 7
Wed Nov 30 11:16:08 2016 [Z0][ImM][D]: Datastore system (0) successfully monitored.
Wed Nov 30 11:16:08 2016 [Z0][ImM][D]: Datastore netapp_system (106) successfully monitored.
Wed Nov 30 11:16:08 2016 [Z0][InM][D]: Host 10.135.195.93 (0) successfully monitored.
Wed Nov 30 11:16:13 2016 [Z0][ReM][D]: Req:9968 UID:0 VirtualMachinePoolInfo invoked , -2, 0, -2000, -1
Wed Nov 30 11:16:13 2016 [Z0][ReM][D]: Req:9968 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL>4</…"
Wed Nov 30 11:16:16 2016 [Z0][ReM][D]: Req:5728 UID:0 VirtualMachineInfo invoked , 7
Wed Nov 30 11:16:16 2016 [Z0][ReM][D]: Req:5728 UID:0 VirtualMachineInfo result SUCCESS, "70…"
Wed Nov 30 11:16:23 2016 [Z0][ReM][D]: Req:1232 UID:0 VirtualMachineInfo invoked , 7
Wed Nov 30 11:16:23 2016 [Z0][ReM][D]: Req:1232 UID:0 VirtualMachineInfo result SUCCESS, "70…"
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:4608 UID:0 VirtualMachinePoolInfo invoked , -2, -1, -1, -1
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:4608 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL>4</…"
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:320 UID:0 VirtualMachinePoolInfo invoked , -2, -1, -1, -1
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:320 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL>4</…"
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:2160 UID:0 DatastorePoolInfo invoked
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:2160 UID:0 DatastorePoolInfo result SUCCESS, "<DATASTORE_POOL><DAT…"
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:1520 UID:0 DatastorePoolInfo invoked
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:1520 UID:0 DatastorePoolInfo result SUCCESS, "<DATASTORE_POOL><DAT…"
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:7104 UID:0 HostPoolInfo invoked
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:7104 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><ID…"
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:6864 UID:0 ClusterPoolInfo invoked
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:6864 UID:0 ClusterPoolInfo result SUCCESS, "<CLUSTER_POOL><CLUST…"
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:80 UID:0 AclInfo invoked
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:80 UID:0 AclInfo result SUCCESS, "<ACL_POOL>0…"
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:3904 UID:0 VirtualMachineDeploy invoked , 7, 0, false, 106
Wed Nov 30 11:16:28 2016 [Z0][DiM][D]: Deploying VM 7
Wed Nov 30 11:16:28 2016 [Z0][ReM][D]: Req:3904 UID:0 VirtualMachineDeploy result SUCCESS, 7
Wed Nov 30 11:16:28 2016 [Z0][ImM][D]: Datastore system (0) successfully monitored.
Wed Nov 30 11:16:28 2016 [Z0][ImM][D]: Datastore netapp_system (106) successfully monitored.
Wed Nov 30 11:16:28 2016 [Z0][InM][D]: Host 10.135.195.93 (0) successfully monitored.
Wed Nov 30 11:16:28 2016 [Z0][VMM][I]: --Mark–
Wed Nov 30 11:16:29 2016 [Z0][TM][D]: Message received: TRANSFER SUCCESS 7 -

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: LOG I 7 ExitCode: 0

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: LOG I 7 Successfully execute network driver operation: pre.

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: LOG I 7 Command execution fail: cat << EOT | /var/tmp/one/vmm/kvm/deploy ‘/var/lib/one//datastores/106/7/deployment.0’ ‘10.135.195.93’ 7 10.135.195.93

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: LOG I 7 error: Failed to create domain from /var/lib/one//datastores/106/7/deployment.0

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: LOG I 7 error: XML error: expected unicast mac address, found multicast ‘09:01:28:9b:12:03’

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: LOG E 7 Could not create domain from /var/lib/one//datastores/106/7/deployment.0

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: LOG I 7 ExitCode: 255

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: LOG I 7 Failed to execute virtualization driver operation: deploy.

Wed Nov 30 11:16:30 2016 [Z0][VMM][D]: Message received: DEPLOY FAILURE 7 Could not create domain from /var/lib/one//datastores/106/7/deployment.0

Wed Nov 30 11:16:34 2016 [Z0][ImM][I]: --Mark–
Wed Nov 30 11:16:40 2016 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 12 -

so I noticed in OpenNebula, when I created a datastore, the base path comes up in a strange way:

/var/lib/one//datastores/107 (NOTE THE double slash). I’m not sure if this is causing this script to fail: /var/tmp/one/vmm/kvm/deploy (THIS IS THE FIRST ERROR, see above)

/var/tmp/one/vmm/kvm/deploy script content:
[root@localhost 8]# cat /var/tmp/one/vmm/kvm/deploy
#!/bin/bash

I removed the script header…

source $(dirname $0)/kvmrc
source $(dirname $0)/…/…/scripts_common.sh

domain=$1

mkdir -p dirname $domain
cat > $domain

data=virsh --connect $LIBVIRT_URI create $domain

if [ “x$?” = “x0” ]; then
echo $data | sed ‘s/Domain //’ | sed 's/ created from .*$//'
else
error_message "Could not create domain from $domain"
exit -1
fi

Could someone please help me with this problem? Thanks.

Hi,

I think you must resolve this error message

Your libvirt is refusing the NIC mac address because the least significant bit of the first byte in the mac address is set to 1 which defines multicast MAC.

Kind Regards,
Anton Todorov