StartCommand failed due to Exception: java.lang.NullPointerException

[Bernhard Dübi <1l...@gmail.com>]

Hi,

we have a CloudStack 4.3.2 installation with 152 XenServer 6.2.0 hosts
organized in 2 zones. We use local disks as primary storage and NFS shares
as secondary storage. So good so far.
Now I created a new zone and added a VMware cluster with 2 ESX 5.5 hosts. I
configured 2 NFS shares as primary and secondary storage and each ESX has a
local data store.
Now I try to deploy a VM from an ISO image. The ISO image is registered as
template 311. Eventually I want to deploy VMs to the NFS store but for a
start I think it's easier to start with the local data stores.
Unfortunately the deployment fails:


2015-12-20 10:13:38,289 DEBUG [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) find VM i-2-4785-VM on host
2015-12-20 10:13:38,289 INFO  [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) VM i-2-4785-VM not found in host
cache
2015-12-20 10:13:38,290 DEBUG [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) load VM cache on host
2015-12-20 10:13:38,505 INFO  [c.c.h.v.m.DatastoreMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) Folder sles12-tpl19 does not
exist on datastore
2015-12-20 10:13:38,505 INFO  [c.c.h.v.m.HypervisorHostHelper]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) Create blank VM. cpuCount: 2,
cpuSpeed(MHz): 2500, mem(Mb): 2048
2015-12-20 10:13:42,365 DEBUG [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) find VM sles12-tpl19 on host
2015-12-20 10:13:42,394 INFO  [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) VM sles12-tpl19 not found in host
cache
2015-12-20 10:13:42,396 DEBUG [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) load VM cache on host
2015-12-20 10:13:42,478 DEBUG [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) find VM i-2-4785-VM on host
2015-12-20 10:13:42,478 INFO  [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) VM i-2-4785-VM not found in host
cache
2015-12-20 10:13:42,478 DEBUG [c.c.h.v.m.HostMO]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) load VM cache on host
2015-12-20 10:13:43,257 WARN  [c.c.h.v.r.VmwareResource]
(DirectAgent-18:ctx-2634c6ef 10.30.23.40) StartCommand failed due to
Exception: java.lang.NullPointerException


you can find the full log of the deployment at
http://www.due.bi/pub/management-server-sles12-tpl19.log

Any idea what's wrong? How can I get more info on the problem?

What I can see on the data store is that the ROOT disk of VM i-2-4785 is
created in the root of the data store and not in the folder sles12-tpl19.
Is that correct / expected?
While the deployment is running the folder sles12-tpl19 is created and the
VM appears in the VMware Virtual Center for a short moment before it's
destroyed again.
I was looking at the logs of the Virtual Center but they don't get updated
while the deployment is running. Maybe I need to ajust the log level in the
VC. Any hints?
I was also running an strace on the management server but it contains
mostly read/write commands with binary content

I'm stuck. I'm looking now for a week at the logs of the management server
but can't see why it's failing. Maybe I'm looking in the wrong place. Maybe
the problem's in the database or one of the system vms.

Regards
Bernhard